Reply To: How to make diversion to a external number correctly

VitalPBX Community Support General Discussion How to make diversion to a external number correctly Reply To: How to make diversion to a external number correctly

    rogerfor
    Participant
    Up
    0
    Down
    This is log if helps.

    thank for your time to help me

    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-3@cos-all-post:2] Gosub("SIP/109-0000006c", "sub-construct-cid,s-external,1") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:1] NoOp("SIP/109-0000006c", "Setting External CID") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:2] Set("SIP/109-0000006c", "EXTENSION_NUMBER=109") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:3] GotoIf("SIP/109-0000006c", "0?return") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:4] GotoIf("SIP/109-0000006c", "0?return") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:5] Set("SIP/109-0000006c", "EMERGENCY_CALL=no") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:6] Set("SIP/109-0000006c", "EMERGENCY_CID=") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:7] ExecIf("SIP/109-0000006c", "1?Set(EMERGENCY_CID="Angel" <109>)") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:8] ExecIf("SIP/109-0000006c", "1?Set(CALLERID(all)="Angel" <109>)") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:9] GotoIf("SIP/109-0000006c", "0?:info") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx_builtins.c: Goto (sub-construct-cid,s-external,11)
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:11] NoOp("SIP/109-0000006c", "CID Name: Angel, CID Number: 109") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:12] Set("SIP/109-0000006c", "SEND_CID=yes") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:13] ExecIf("SIP/109-0000006c", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:14] ExecIf("SIP/109-0000006c", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:15] Set("SIP/109-0000006c", "__EXT_CID_CONSTRUCTED=yes") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:16] Set("SIP/109-0000006c", "CDR(source)=109") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-external@sub-construct-cid:17] Return("SIP/109-0000006c", "") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-3@cos-all-post:3] Gosub("SIP/109-0000006c", "trk-4,917575777,1(from-trk-grp)") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4:1] Set("SIP/109-0000006c", "_NTD=917575777") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4:2] Gosub("SIP/109-0000006c", "trk-4-dial,917575777,1(from-trk-grp)") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:1] NoOp("SIP/109-0000006c", "Trunk: nbdt") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:2] NoOp("SIP/109-0000006c", "Dialing: 917575777") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:3] Set("SIP/109-0000006c", "CDR(trunk)=4") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:4] Gosub("SIP/109-0000006c", "sub-setup-call-type,s,1(outgoing,no)") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("SIP/109-0000006c", "Determinating Call Type") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("SIP/109-0000006c", "0?return") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("SIP/109-0000006c", "s-outgoing,1(no)") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("SIP/109-0000006c", "Outgoing Call") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("SIP/109-0000006c", "__CALL_TYPE=3") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("SIP/109-0000006c", "__CALL_TYPE_LABEL=OUT") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("SIP/109-0000006c", "0?s-transit,1") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("SIP/109-0000006c", "") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-setup-call-type:4] Set("SIP/109-0000006c", "__CALL_TYPE_CONFIGURED=yes") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-setup-call-type:5] Set("SIP/109-0000006c", "CDR(calltype)=3") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-setup-call-type:6] Return("SIP/109-0000006c", "") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:5] NoOp("SIP/109-0000006c", "Configuring Trunk Parameters") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:6] ExecIf("SIP/109-0000006c", "1?Set(CHANNEL(musicclass)=default):") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:7] Set("SIP/109-0000006c", "__TRUNK_MOH_SET=yes") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:8] GotoIf("SIP/109-0000006c", "0?after-overwrite") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:9] Set("SIP/109-0000006c", "CALLERID(all)="" <886300307>") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:10] NoOp("SIP/109-0000006c", "CID to be sent: "" <886300307>") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:11] Set("SIP/109-0000006c", "__TRKDIALOPTIONS=r") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:12] Set("SIP/109-0000006c", "ORIGINATOR=109") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:13] Set("SIP/109-0000006c", "RING_TIME=90") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:14] Set("SIP/109-0000006c", "CALLED=917575777") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:15] Set("SIP/109-0000006c", "__CIDNUM=886300307") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:16] Set("SIP/109-0000006c", "__CIDNAME=") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:17] Set("SIP/109-0000006c", "FORCE_RECORDING=no") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] pbx.c: Executing [917575777@trk-4-dial:18] Dial("SIP/109-0000006c", "SIP/ /917575777,90,rU(sub-call-recording^77689c7692b352e7^109^917575777^no)") in new stack
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] netsock2.c: Using SIP RTP TOS bits 184
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] netsock2.c: Using SIP RTP CoS mark 5
    [2020-07-08 16:11:33] VERBOSE[18538][C-0000003a] app_dial.c: Called SIP/ /917575777
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] app_dial.c: SIP/ -0000006d is making progress passing it to SIP/109-0000006c
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] app_dial.c: SIP/ -0000006d answered SIP/109-0000006c
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] app_stack.c: SIP/ -0000006d Internal Gosub(sub-call-recording,s,1(77689c7692b352e7,109,917575777,no)) start
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:1] NoOp("SIP/ -0000006d", "Check if call will be recorded on tenant 77689c7692b352e7") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:2] NoOp("SIP/ -0000006d", "Call Type: 3 ") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:3] Set("SIP/ -0000006d", "TENANT=77689c7692b352e7") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:4] Set("SIP/ -0000006d", "CALLER=109") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:5] Set("SIP/ -0000006d", "CALLEE=917575777") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:6] Set("SIP/ -0000006d", "APP_FORCE=no") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:7] GotoIf("SIP/ -0000006d", "1?:rec_no_available") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:8] GotoIf("SIP/ -0000006d", "0?perform-recording") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:9] GotoIf("SIP/ -0000006d", "0?perform-recording") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:10] GotoIf("SIP/ -0000006d", "0?perform-recording") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:11] GotoIf("SIP/ -0000006d", "0?perform-recording") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:12] GotoIf("SIP/ -0000006d", "0?perform-recording") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:13] Goto("SIP/ -0000006d", "return") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx_builtins.c: Goto (sub-call-recording,s,17)
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@sub-call-recording:17] Return("SIP/ -0000006d", "") in new stack
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] app_stack.c: Spawn extension (trk-4-in, , 1) exited non-zero on 'SIP/ -0000006d'
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] app_stack.c: SIP/ -0000006d Internal Gosub(sub-call-recording,s,1(77689c7692b352e7,109,917575777,no)) complete GOSUB_RETVAL=
    [2020-07-08 16:11:34] VERBOSE[18541][C-0000003a] bridge_channel.c: Channel SIP/ -0000006d joined 'simple_bridge' basic-bridge <ec5741f6-206f-4797-9b93-2776cc9ddc34>
    [2020-07-08 16:11:34] VERBOSE[18538][C-0000003a] bridge_channel.c: Channel SIP/109-0000006c joined 'simple_bridge' basic-bridge <ec5741f6-206f-4797-9b93-2776cc9ddc34>
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] bridge_channel.c: Channel SIP/109-0000006c left 'simple_bridge' basic-bridge <ec5741f6-206f-4797-9b93-2776cc9ddc34>
    [2020-07-08 16:11:44] VERBOSE[18541][C-0000003a] bridge_channel.c: Channel SIP/ -0000006d left 'simple_bridge' basic-bridge <ec5741f6-206f-4797-9b93-2776cc9ddc34>
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Spawn extension (trk-4-dial, 917575777, 18) exited non-zero on 'SIP/109-0000006c'
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Executing [h@trk-4-dial:1] Gosub("SIP/109-0000006c", "dynamic-routing-out,s,1(109,917575777,ANSWER,4)") in new stack
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@dynamic-routing-out:1] NoOp("SIP/109-0000006c", "Test if save on dynamic routing list") in new stack
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@dynamic-routing-out:2] Set("SIP/109-0000006c", "CALLER=109") in new stack
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@dynamic-routing-out:3] Set("SIP/109-0000006c", "CALLED=917575777") in new stack
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@dynamic-routing-out:4] Set("SIP/109-0000006c", "STATUS=ANSWER") in new stack
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@dynamic-routing-out:5] Set("SIP/109-0000006c", "TRKID=4") in new stack
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@dynamic-routing-out:6] Set("SIP/109-0000006c", "ALLOW_DR=no") in new stack
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@dynamic-routing-out:7] Set("SIP/109-0000006c", "DYNROUTING_OMC=yes") in new stack
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@dynamic-routing-out:8] GotoIf("SIP/109-0000006c", "0?:comeback") in new stack
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx_builtins.c: Goto (dynamic-routing-out,s,10)
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Executing [s@dynamic-routing-out:10] Return("SIP/109-0000006c", "") in new stack
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Executing [h@trk-4-dial:2] Hangup("SIP/109-0000006c", "") in new stack
    [2020-07-08 16:11:44] VERBOSE[18538][C-0000003a] pbx.c: Spawn extension (trk-4-dial, h, 2) exited non-zero on 'SIP/109-0000006c'
    [2020-07-08 16:14:10] VERBOSE[17452][C-0000003b] netsock2.c: Using SIP RTP TOS bits 184
    [2020-07-08 16:14:10] VERBOSE[17452][C-0000003b] netsock2.c: Using SIP RTP CoS mark 5
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@cos-all:1] NoOp("SIP/109-0000006e", "More than on digit pattern") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@cos-all:2] Gosub("SIP/109-0000006e", "s,1(901335533)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:1] Set("SIP/109-0000006e", "EXTENSION=901335533") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:2] NoOp("SIP/109-0000006e", "Dialing 901335533 from 109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:3] Gosub("SIP/109-0000006e", "sub-set-global-vars,s,1(77689c7692b352e7,901335533,parking-1)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-global-vars:1] NoOp("SIP/109-0000006e", "Setting Global Vars") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-global-vars:2] Set("SIP/109-0000006e", "TENANT_PATH=77689c7692b352e7") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-global-vars:3] Set("SIP/109-0000006e", "PREFIX=") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-global-vars:4] Set("SIP/109-0000006e", "__PICKUPMARK=901335533") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-global-vars:5] Set("SIP/109-0000006e", "__PARKINGLOT=parking-1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-global-vars:6] Set("SIP/109-0000006e", "__DYNAMIC_FEATURES=custom-features") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-global-vars:7] Set("SIP/109-0000006e", "ICALL=no") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-global-vars:8] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:4] Gosub("SIP/109-0000006e", "sub-set-call-vars,s,1(77689c7692b352e7,901335533,1,cos-all,ARS-all)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-call-vars:1] NoOp("SIP/109-0000006e", "Setting Call Vars") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-call-vars:2] Set("SIP/109-0000006e", "_TENANT=77689c7692b352e7") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-call-vars:3] Set("SIP/109-0000006e", "_CALL_DESTINATION=901335533") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-call-vars:4] Set("SIP/109-0000006e", "_COS_ID=1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-call-vars:5] Set("SIP/109-0000006e", "_COS_NAME=cos-all") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-call-vars:6] Set("SIP/109-0000006e", "__TRANSFER_CONTEXT=cos-all") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-call-vars:7] Set("SIP/109-0000006e", "_ARS=ARS-all") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-call-vars:8] Set("SIP/109-0000006e", "CDR(tenant)=vitalpbx") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-call-vars:9] Set("SIP/109-0000006e", "CDR(destination)=901335533") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-call-vars:10] Set("SIP/109-0000006e", "__CALLID=1594217650.199") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-set-call-vars:11] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:5] Gosub("SIP/109-0000006e", "sub-construct-cid,s,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:1] NoOp("SIP/109-0000006e", "Building CID") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:2] Set("SIP/109-0000006e", "_CALL_SOURCE=109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:3] Set("SIP/109-0000006e", "_CS=109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:4] GotoIf("SIP/109-0000006e", "0?return") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:5] Gosub("SIP/109-0000006e", "sub-get-device-tree,s,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-get-device-tree:1] NoOp("SIP/109-0000006e", "Configuring Device Tree") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-get-device-tree:2] Set("SIP/109-0000006e", "DEV_TECHNOLOGY=SIP") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-get-device-tree:3] GosubIf("SIP/109-0000006e", "0?s-local,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-get-device-tree:4] GotoIf("SIP/109-0000006e", "0?return") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-get-device-tree:5] GotoIf("SIP/109-0000006e", "0?pjsip") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-get-device-tree:6] GotoIf("SIP/109-0000006e", "0?:digital") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx_builtins.c: Goto (sub-get-device-tree,s,12)
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-get-device-tree:12] Set("SIP/109-0000006e", "DEV_USER=109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-get-device-tree:13] Set("SIP/109-0000006e", "INTERFACE=SIP/109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-get-device-tree:14] Set("SIP/109-0000006e", "DEV_TREE=devices/SIP/109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-get-device-tree:15] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:6] GotoIf("SIP/109-0000006e", "0?return") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:7] Set("SIP/109-0000006e", "_CALL_SOURCE=109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:8] Set("SIP/109-0000006e", "_CS=109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:9] ExecIf("SIP/109-0000006e", "1?Set(CALLERID(all)="Angel" <109>)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:10] Set("SIP/109-0000006e", "_CID_CONSTRUCTED=yes") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:11] NoOp("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:12] Set("SIP/109-0000006e", "CDR(source)=109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-construct-cid:13] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:6] Gosub("SIP/109-0000006e", "set-global-tenant-vars,s,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@set-global-tenant-vars:1] NoOp("SIP/109-0000006e", "Setting Global Vars for vitalpbx Tenant") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@set-global-tenant-vars:2] Set("SIP/109-0000006e", "__TENANT_PATH=77689c7692b352e7") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@set-global-tenant-vars:3] Set("SIP/109-0000006e", "__TENANT_PREFIX=") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@set-global-tenant-vars:4] Set("SIP/109-0000006e", "__QUEUE_AGENTS_CONTEXT=queue-call-to-agents") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@set-global-tenant-vars:5] Set("SIP/109-0000006e", "__FOLLOWME_CONTEXT=ext-followme") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@set-global-tenant-vars:6] Set("SIP/109-0000006e", "__HINTS_CONTEXT=extension-hints") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@set-global-tenant-vars:7] Set("SIP/109-0000006e", "__DEFAULT_COS=cos-all") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@set-global-tenant-vars:8] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:7] GotoIf("SIP/109-0000006e", "0?local-dialing") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:8] NoOp("SIP/109-0000006e", "Check if is an Emergency Call") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:9] GotoIf("SIP/109-0000006e", "0?emergency-calls,901335533,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:10] Gosub("SIP/109-0000006e", "sub-lockphone-check,s,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-lockphone-check:1] NoOp("SIP/109-0000006e", "SUB: LOCK PHONE CHECK") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-lockphone-check:2] GotoIf("SIP/109-0000006e", "0?unlock") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-lockphone-check:3] GotoIf("SIP/109-0000006e", "0?unlock") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-lockphone-check:4] GotoIf("SIP/109-0000006e", "0?unlock") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-lockphone-check:5] GotoIf("SIP/109-0000006e", "0?unlock") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-lockphone-check:6] GotoIf("SIP/109-0000006e", "0?:unlock") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx_builtins.c: Goto (sub-lockphone-check,s,12)
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-lockphone-check:12] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:11] Gosub("SIP/109-0000006e", "sub-local-dialing,s,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-local-dialing:1] NoOp("SIP/109-0000006e", "Trying to determine if is a local extension") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-local-dialing:2] GotoIf("SIP/109-0000006e", "0?:return") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx_builtins.c: Goto (sub-local-dialing,s,4)
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-local-dialing:4] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:12] GotoIf("SIP/109-0000006e", "0?end-call") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@cos-all:13] Goto("SIP/109-0000006e", "cos-all-post,901335533,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx_builtins.c: Goto (cos-all-post,901335533,1)
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@cos-all-post:1] NoOp("SIP/109-0000006e", "NBDTSIP") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@cos-all-post:2] Gosub("SIP/109-0000006e", "sub-setup-call-type,s,1(outgoing)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("SIP/109-0000006e", "Determinating Call Type") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("SIP/109-0000006e", "0?return") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("SIP/109-0000006e", "s-outgoing,1()") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("SIP/109-0000006e", "Outgoing Call") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("SIP/109-0000006e", "__CALL_TYPE=3") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("SIP/109-0000006e", "__CALL_TYPE_LABEL=OUT") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("SIP/109-0000006e", "0?s-transit,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:4] Set("SIP/109-0000006e", "__CALL_TYPE_CONFIGURED=yes") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:5] Set("SIP/109-0000006e", "CDR(calltype)=3") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:6] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@cos-all-post:3] NoOp("SIP/109-0000006e", "Start to Dial Trunks") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@cos-all-post:4] Set("SIP/109-0000006e", "DNID=901335533") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@cos-all-post:5] Goto("SIP/109-0000006e", "s-3,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx_builtins.c: Goto (cos-all-post,s-3,1)
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-3@cos-all-post:1] Set("SIP/109-0000006e", "__CONFIGURED_BY_TENANT=yes") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-3@cos-all-post:2] Gosub("SIP/109-0000006e", "sub-construct-cid,s-external,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:1] NoOp("SIP/109-0000006e", "Setting External CID") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:2] Set("SIP/109-0000006e", "EXTENSION_NUMBER=109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:3] GotoIf("SIP/109-0000006e", "0?return") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:4] GotoIf("SIP/109-0000006e", "0?return") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:5] Set("SIP/109-0000006e", "EMERGENCY_CALL=no") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:6] Set("SIP/109-0000006e", "EMERGENCY_CID=") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:7] ExecIf("SIP/109-0000006e", "1?Set(EMERGENCY_CID="Angel" <109>)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:8] ExecIf("SIP/109-0000006e", "1?Set(CALLERID(all)="Angel" <109>)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:9] GotoIf("SIP/109-0000006e", "0?:info") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx_builtins.c: Goto (sub-construct-cid,s-external,11)
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:11] NoOp("SIP/109-0000006e", "CID Name: Angel, CID Number: 109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:12] Set("SIP/109-0000006e", "SEND_CID=yes") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:13] ExecIf("SIP/109-0000006e", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:14] ExecIf("SIP/109-0000006e", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:15] Set("SIP/109-0000006e", "__EXT_CID_CONSTRUCTED=yes") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:16] Set("SIP/109-0000006e", "CDR(source)=109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-external@sub-construct-cid:17] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-3@cos-all-post:3] Gosub("SIP/109-0000006e", "trk-4,901335533,1(from-trk-grp)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4:1] Set("SIP/109-0000006e", "_NTD=901335533") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4:2] Gosub("SIP/109-0000006e", "trk-4-dial,901335533,1(from-trk-grp)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:1] NoOp("SIP/109-0000006e", "Trunk: nbdt") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:2] NoOp("SIP/109-0000006e", "Dialing: 901335533") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:3] Set("SIP/109-0000006e", "CDR(trunk)=4") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:4] Gosub("SIP/109-0000006e", "sub-setup-call-type,s,1(outgoing,no)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("SIP/109-0000006e", "Determinating Call Type") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("SIP/109-0000006e", "0?return") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("SIP/109-0000006e", "s-outgoing,1(no)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("SIP/109-0000006e", "Outgoing Call") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("SIP/109-0000006e", "__CALL_TYPE=3") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("SIP/109-0000006e", "__CALL_TYPE_LABEL=OUT") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("SIP/109-0000006e", "0?s-transit,1") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:4] Set("SIP/109-0000006e", "__CALL_TYPE_CONFIGURED=yes") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:5] Set("SIP/109-0000006e", "CDR(calltype)=3") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-setup-call-type:6] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:5] NoOp("SIP/109-0000006e", "Configuring Trunk Parameters") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:6] ExecIf("SIP/109-0000006e", "1?Set(CHANNEL(musicclass)=default):") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:7] Set("SIP/109-0000006e", "__TRUNK_MOH_SET=yes") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:8] GotoIf("SIP/109-0000006e", "0?after-overwrite") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:9] Set("SIP/109-0000006e", "CALLERID(all)="" <886300307>") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:10] NoOp("SIP/109-0000006e", "CID to be sent: "" <886300307>") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:11] Set("SIP/109-0000006e", "__TRKDIALOPTIONS=r") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:12] Set("SIP/109-0000006e", "ORIGINATOR=109") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:13] Set("SIP/109-0000006e", "RING_TIME=90") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:14] Set("SIP/109-0000006e", "CALLED=901335533") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:15] Set("SIP/109-0000006e", "__CIDNUM=886300307") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:16] Set("SIP/109-0000006e", "__CIDNAME=") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:17] Set("SIP/109-0000006e", "FORCE_RECORDING=no") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] pbx.c: Executing [901335533@trk-4-dial:18] Dial("SIP/109-0000006e", "SIP/ /901335533,90,rU(sub-call-recording^77689c7692b352e7^109^901335533^no)") in new stack
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] netsock2.c: Using SIP RTP TOS bits 184
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] netsock2.c: Using SIP RTP CoS mark 5
    [2020-07-08 16:14:10] VERBOSE[18878][C-0000003b] app_dial.c: Called SIP/ /901335533
    [2020-07-08 16:14:13] VERBOSE[18878][C-0000003b] app_dial.c: SIP/ -0000006f is making progress passing it to SIP/109-0000006e
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] app_dial.c: SIP/ -0000006f answered SIP/109-0000006e
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] app_stack.c: SIP/ -0000006f Internal Gosub(sub-call-recording,s,1(77689c7692b352e7,109,901335533,no)) start
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:1] NoOp("SIP/ -0000006f", "Check if call will be recorded on tenant 77689c7692b352e7") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:2] NoOp("SIP/ -0000006f", "Call Type: 3 ") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:3] Set("SIP/ -0000006f", "TENANT=77689c7692b352e7") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:4] Set("SIP/ -0000006f", "CALLER=109") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:5] Set("SIP/ -0000006f", "CALLEE=901335533") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:6] Set("SIP/ -0000006f", "APP_FORCE=no") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:7] GotoIf("SIP/ -0000006f", "1?:rec_no_available") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:8] GotoIf("SIP/ -0000006f", "0?perform-recording") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:9] GotoIf("SIP/ -0000006f", "0?perform-recording") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:10] GotoIf("SIP/ -0000006f", "0?perform-recording") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:11] GotoIf("SIP/ -0000006f", "0?perform-recording") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:12] GotoIf("SIP/ -0000006f", "0?perform-recording") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:13] Goto("SIP/ -0000006f", "return") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx_builtins.c: Goto (sub-call-recording,s,17)
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@sub-call-recording:17] Return("SIP/ -0000006f", "") in new stack
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] app_stack.c: Spawn extension (trk-4-in, , 1) exited non-zero on 'SIP/ -0000006f'
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] app_stack.c: SIP/ -0000006f Internal Gosub(sub-call-recording,s,1(77689c7692b352e7,109,901335533,no)) complete GOSUB_RETVAL=
    [2020-07-08 16:14:14] VERBOSE[18886][C-0000003b] bridge_channel.c: Channel SIP/ -0000006f joined 'simple_bridge' basic-bridge <9f553fec-5869-4aaf-b133-d4ce15b6fc33>
    [2020-07-08 16:14:14] VERBOSE[18878][C-0000003b] bridge_channel.c: Channel SIP/109-0000006e joined 'simple_bridge' basic-bridge <9f553fec-5869-4aaf-b133-d4ce15b6fc33>
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] bridge_channel.c: Channel SIP/109-0000006e left 'simple_bridge' basic-bridge <9f553fec-5869-4aaf-b133-d4ce15b6fc33>
    [2020-07-08 16:14:40] VERBOSE[18886][C-0000003b] bridge_channel.c: Channel SIP/ -0000006f left 'simple_bridge' basic-bridge <9f553fec-5869-4aaf-b133-d4ce15b6fc33>
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Spawn extension (trk-4-dial, 901335533, 18) exited non-zero on 'SIP/109-0000006e'
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Executing [h@trk-4-dial:1] Gosub("SIP/109-0000006e", "dynamic-routing-out,s,1(109,901335533,ANSWER,4)") in new stack
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@dynamic-routing-out:1] NoOp("SIP/109-0000006e", "Test if save on dynamic routing list") in new stack
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@dynamic-routing-out:2] Set("SIP/109-0000006e", "CALLER=109") in new stack
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@dynamic-routing-out:3] Set("SIP/109-0000006e", "CALLED=901335533") in new stack
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@dynamic-routing-out:4] Set("SIP/109-0000006e", "STATUS=ANSWER") in new stack
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@dynamic-routing-out:5] Set("SIP/109-0000006e", "TRKID=4") in new stack
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@dynamic-routing-out:6] Set("SIP/109-0000006e", "ALLOW_DR=no") in new stack
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@dynamic-routing-out:7] Set("SIP/109-0000006e", "DYNROUTING_OMC=yes") in new stack
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@dynamic-routing-out:8] GotoIf("SIP/109-0000006e", "0?:comeback") in new stack
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx_builtins.c: Goto (dynamic-routing-out,s,10)
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Executing [s@dynamic-routing-out:10] Return("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Executing [h@trk-4-dial:2] Hangup("SIP/109-0000006e", "") in new stack
    [2020-07-08 16:14:40] VERBOSE[18878][C-0000003b] pbx.c: Spawn extension (trk-4-dial, h, 2) exited non-zero on 'SIP/109-0000006e'
    [2020-07-08 16:14:55] VERBOSE[17452][C-0000003c] netsock2.c: Using SIP RTP TOS bits 184
    [2020-07-08 16:14:55] VERBOSE[17452][C-0000003c] netsock2.c: Using SIP RTP CoS mark 5
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@cos-all:1] NoOp("SIP/109-00000070", "More than on digit pattern") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@cos-all:2] Gosub("SIP/109-00000070", "s,1(901200345)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:1] Set("SIP/109-00000070", "EXTENSION=901200345") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:2] NoOp("SIP/109-00000070", "Dialing 901200345 from 109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:3] Gosub("SIP/109-00000070", "sub-set-global-vars,s,1(77689c7692b352e7,901200345,parking-1)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-global-vars:1] NoOp("SIP/109-00000070", "Setting Global Vars") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-global-vars:2] Set("SIP/109-00000070", "TENANT_PATH=77689c7692b352e7") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-global-vars:3] Set("SIP/109-00000070", "PREFIX=") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-global-vars:4] Set("SIP/109-00000070", "__PICKUPMARK=901200345") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-global-vars:5] Set("SIP/109-00000070", "__PARKINGLOT=parking-1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-global-vars:6] Set("SIP/109-00000070", "__DYNAMIC_FEATURES=custom-features") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-global-vars:7] Set("SIP/109-00000070", "ICALL=no") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-global-vars:8] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:4] Gosub("SIP/109-00000070", "sub-set-call-vars,s,1(77689c7692b352e7,901200345,1,cos-all,ARS-all)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-call-vars:1] NoOp("SIP/109-00000070", "Setting Call Vars") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-call-vars:2] Set("SIP/109-00000070", "_TENANT=77689c7692b352e7") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-call-vars:3] Set("SIP/109-00000070", "_CALL_DESTINATION=901200345") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-call-vars:4] Set("SIP/109-00000070", "_COS_ID=1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-call-vars:5] Set("SIP/109-00000070", "_COS_NAME=cos-all") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-call-vars:6] Set("SIP/109-00000070", "__TRANSFER_CONTEXT=cos-all") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-call-vars:7] Set("SIP/109-00000070", "_ARS=ARS-all") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-call-vars:8] Set("SIP/109-00000070", "CDR(tenant)=vitalpbx") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-call-vars:9] Set("SIP/109-00000070", "CDR(destination)=901200345") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-call-vars:10] Set("SIP/109-00000070", "__CALLID=1594217695.202") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-set-call-vars:11] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:5] Gosub("SIP/109-00000070", "sub-construct-cid,s,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:1] NoOp("SIP/109-00000070", "Building CID") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:2] Set("SIP/109-00000070", "_CALL_SOURCE=109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:3] Set("SIP/109-00000070", "_CS=109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:4] GotoIf("SIP/109-00000070", "0?return") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:5] Gosub("SIP/109-00000070", "sub-get-device-tree,s,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-get-device-tree:1] NoOp("SIP/109-00000070", "Configuring Device Tree") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-get-device-tree:2] Set("SIP/109-00000070", "DEV_TECHNOLOGY=SIP") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-get-device-tree:3] GosubIf("SIP/109-00000070", "0?s-local,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-get-device-tree:4] GotoIf("SIP/109-00000070", "0?return") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-get-device-tree:5] GotoIf("SIP/109-00000070", "0?pjsip") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-get-device-tree:6] GotoIf("SIP/109-00000070", "0?:digital") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx_builtins.c: Goto (sub-get-device-tree,s,12)
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-get-device-tree:12] Set("SIP/109-00000070", "DEV_USER=109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-get-device-tree:13] Set("SIP/109-00000070", "INTERFACE=SIP/109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-get-device-tree:14] Set("SIP/109-00000070", "DEV_TREE=devices/SIP/109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-get-device-tree:15] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:6] GotoIf("SIP/109-00000070", "0?return") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:7] Set("SIP/109-00000070", "_CALL_SOURCE=109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:8] Set("SIP/109-00000070", "_CS=109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:9] ExecIf("SIP/109-00000070", "1?Set(CALLERID(all)="Angel" <109>)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:10] Set("SIP/109-00000070", "_CID_CONSTRUCTED=yes") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:11] NoOp("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:12] Set("SIP/109-00000070", "CDR(source)=109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-construct-cid:13] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:6] Gosub("SIP/109-00000070", "set-global-tenant-vars,s,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@set-global-tenant-vars:1] NoOp("SIP/109-00000070", "Setting Global Vars for vitalpbx Tenant") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@set-global-tenant-vars:2] Set("SIP/109-00000070", "__TENANT_PATH=77689c7692b352e7") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@set-global-tenant-vars:3] Set("SIP/109-00000070", "__TENANT_PREFIX=") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@set-global-tenant-vars:4] Set("SIP/109-00000070", "__QUEUE_AGENTS_CONTEXT=queue-call-to-agents") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@set-global-tenant-vars:5] Set("SIP/109-00000070", "__FOLLOWME_CONTEXT=ext-followme") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@set-global-tenant-vars:6] Set("SIP/109-00000070", "__HINTS_CONTEXT=extension-hints") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@set-global-tenant-vars:7] Set("SIP/109-00000070", "__DEFAULT_COS=cos-all") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@set-global-tenant-vars:8] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:7] GotoIf("SIP/109-00000070", "0?local-dialing") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:8] NoOp("SIP/109-00000070", "Check if is an Emergency Call") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:9] GotoIf("SIP/109-00000070", "0?emergency-calls,901200345,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:10] Gosub("SIP/109-00000070", "sub-lockphone-check,s,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-lockphone-check:1] NoOp("SIP/109-00000070", "SUB: LOCK PHONE CHECK") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-lockphone-check:2] GotoIf("SIP/109-00000070", "0?unlock") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-lockphone-check:3] GotoIf("SIP/109-00000070", "0?unlock") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-lockphone-check:4] GotoIf("SIP/109-00000070", "0?unlock") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-lockphone-check:5] GotoIf("SIP/109-00000070", "0?unlock") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-lockphone-check:6] GotoIf("SIP/109-00000070", "0?:unlock") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx_builtins.c: Goto (sub-lockphone-check,s,12)
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-lockphone-check:12] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:11] Gosub("SIP/109-00000070", "sub-local-dialing,s,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-local-dialing:1] NoOp("SIP/109-00000070", "Trying to determine if is a local extension") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-local-dialing:2] GotoIf("SIP/109-00000070", "0?:return") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx_builtins.c: Goto (sub-local-dialing,s,4)
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-local-dialing:4] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:12] GotoIf("SIP/109-00000070", "0?end-call") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@cos-all:13] Goto("SIP/109-00000070", "cos-all-post,901200345,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx_builtins.c: Goto (cos-all-post,901200345,1)
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@cos-all-post:1] NoOp("SIP/109-00000070", "NBDTSIP") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@cos-all-post:2] Gosub("SIP/109-00000070", "sub-setup-call-type,s,1(outgoing)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("SIP/109-00000070", "Determinating Call Type") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("SIP/109-00000070", "0?return") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("SIP/109-00000070", "s-outgoing,1()") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("SIP/109-00000070", "Outgoing Call") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("SIP/109-00000070", "__CALL_TYPE=3") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("SIP/109-00000070", "__CALL_TYPE_LABEL=OUT") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("SIP/109-00000070", "0?s-transit,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:4] Set("SIP/109-00000070", "__CALL_TYPE_CONFIGURED=yes") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:5] Set("SIP/109-00000070", "CDR(calltype)=3") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:6] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@cos-all-post:3] NoOp("SIP/109-00000070", "Start to Dial Trunks") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@cos-all-post:4] Set("SIP/109-00000070", "DNID=901200345") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@cos-all-post:5] Goto("SIP/109-00000070", "s-3,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx_builtins.c: Goto (cos-all-post,s-3,1)
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-3@cos-all-post:1] Set("SIP/109-00000070", "__CONFIGURED_BY_TENANT=yes") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-3@cos-all-post:2] Gosub("SIP/109-00000070", "sub-construct-cid,s-external,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:1] NoOp("SIP/109-00000070", "Setting External CID") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:2] Set("SIP/109-00000070", "EXTENSION_NUMBER=109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:3] GotoIf("SIP/109-00000070", "0?return") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:4] GotoIf("SIP/109-00000070", "0?return") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:5] Set("SIP/109-00000070", "EMERGENCY_CALL=no") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:6] Set("SIP/109-00000070", "EMERGENCY_CID=") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:7] ExecIf("SIP/109-00000070", "1?Set(EMERGENCY_CID="Angel" <109>)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:8] ExecIf("SIP/109-00000070", "1?Set(CALLERID(all)="Angel" <109>)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:9] GotoIf("SIP/109-00000070", "0?:info") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx_builtins.c: Goto (sub-construct-cid,s-external,11)
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:11] NoOp("SIP/109-00000070", "CID Name: Angel, CID Number: 109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:12] Set("SIP/109-00000070", "SEND_CID=yes") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:13] ExecIf("SIP/109-00000070", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:14] ExecIf("SIP/109-00000070", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:15] Set("SIP/109-00000070", "__EXT_CID_CONSTRUCTED=yes") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:16] Set("SIP/109-00000070", "CDR(source)=109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-external@sub-construct-cid:17] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-3@cos-all-post:3] Gosub("SIP/109-00000070", "trk-4,901200345,1(from-trk-grp)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4:1] Set("SIP/109-00000070", "_NTD=901200345") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4:2] Gosub("SIP/109-00000070", "trk-4-dial,901200345,1(from-trk-grp)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:1] NoOp("SIP/109-00000070", "Trunk: nbdt") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:2] NoOp("SIP/109-00000070", "Dialing: 901200345") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:3] Set("SIP/109-00000070", "CDR(trunk)=4") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:4] Gosub("SIP/109-00000070", "sub-setup-call-type,s,1(outgoing,no)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("SIP/109-00000070", "Determinating Call Type") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("SIP/109-00000070", "0?return") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("SIP/109-00000070", "s-outgoing,1(no)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("SIP/109-00000070", "Outgoing Call") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("SIP/109-00000070", "__CALL_TYPE=3") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("SIP/109-00000070", "__CALL_TYPE_LABEL=OUT") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("SIP/109-00000070", "0?s-transit,1") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:4] Set("SIP/109-00000070", "__CALL_TYPE_CONFIGURED=yes") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:5] Set("SIP/109-00000070", "CDR(calltype)=3") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-setup-call-type:6] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:5] NoOp("SIP/109-00000070", "Configuring Trunk Parameters") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:6] ExecIf("SIP/109-00000070", "1?Set(CHANNEL(musicclass)=default):") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:7] Set("SIP/109-00000070", "__TRUNK_MOH_SET=yes") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:8] GotoIf("SIP/109-00000070", "0?after-overwrite") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:9] Set("SIP/109-00000070", "CALLERID(all)="" <886300307>") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:10] NoOp("SIP/109-00000070", "CID to be sent: "" <886300307>") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:11] Set("SIP/109-00000070", "__TRKDIALOPTIONS=r") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:12] Set("SIP/109-00000070", "ORIGINATOR=109") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:13] Set("SIP/109-00000070", "RING_TIME=90") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:14] Set("SIP/109-00000070", "CALLED=901200345") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:15] Set("SIP/109-00000070", "__CIDNUM=886300307") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:16] Set("SIP/109-00000070", "__CIDNAME=") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:17] Set("SIP/109-00000070", "FORCE_RECORDING=no") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] pbx.c: Executing [901200345@trk-4-dial:18] Dial("SIP/109-00000070", "SIP/ /901200345,90,rU(sub-call-recording^77689c7692b352e7^109^901200345^no)") in new stack
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] netsock2.c: Using SIP RTP TOS bits 184
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] netsock2.c: Using SIP RTP CoS mark 5
    [2020-07-08 16:14:55] VERBOSE[18970][C-0000003c] app_dial.c: Called SIP/ /901200345
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] app_dial.c: SIP/ -00000071 is making progress passing it to SIP/109-00000070
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] app_dial.c: SIP/ -00000071 answered SIP/109-00000070
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] app_stack.c: SIP/ -00000071 Internal Gosub(sub-call-recording,s,1(77689c7692b352e7,109,901200345,no)) start
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:1] NoOp("SIP/ -00000071", "Check if call will be recorded on tenant 77689c7692b352e7") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:2] NoOp("SIP/ -00000071", "Call Type: 3 ") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:3] Set("SIP/ -00000071", "TENANT=77689c7692b352e7") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:4] Set("SIP/ -00000071", "CALLER=109") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:5] Set("SIP/ -00000071", "CALLEE=901200345") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:6] Set("SIP/ -00000071", "APP_FORCE=no") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:7] GotoIf("SIP/ -00000071", "1?:rec_no_available") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:8] GotoIf("SIP/ -00000071", "0?perform-recording") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:9] GotoIf("SIP/ -00000071", "0?perform-recording") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:10] GotoIf("SIP/ -00000071", "0?perform-recording") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:11] GotoIf("SIP/ -00000071", "0?perform-recording") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:12] GotoIf("SIP/ -00000071", "0?perform-recording") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:13] Goto("SIP/ -00000071", "return") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx_builtins.c: Goto (sub-call-recording,s,17)
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@sub-call-recording:17] Return("SIP/ -00000071", "") in new stack
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] app_stack.c: Spawn extension (trk-4-in, , 1) exited non-zero on 'SIP/ -00000071'
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] app_stack.c: SIP/ -00000071 Internal Gosub(sub-call-recording,s,1(77689c7692b352e7,109,901200345,no)) complete GOSUB_RETVAL=
    [2020-07-08 16:14:59] VERBOSE[18977][C-0000003c] bridge_channel.c: Channel SIP/ -00000071 joined 'simple_bridge' basic-bridge <76e0ec7c-8d4a-4629-a383-633960397ae2>
    [2020-07-08 16:14:59] VERBOSE[18970][C-0000003c] bridge_channel.c: Channel SIP/109-00000070 joined 'simple_bridge' basic-bridge <76e0ec7c-8d4a-4629-a383-633960397ae2>
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] bridge_channel.c: Channel SIP/109-00000070 left 'simple_bridge' basic-bridge <76e0ec7c-8d4a-4629-a383-633960397ae2>
    [2020-07-08 16:18:54] VERBOSE[18977][C-0000003c] bridge_channel.c: Channel SIP/ -00000071 left 'simple_bridge' basic-bridge <76e0ec7c-8d4a-4629-a383-633960397ae2>
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Spawn extension (trk-4-dial, 901200345, 18) exited non-zero on 'SIP/109-00000070'
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Executing [h@trk-4-dial:1] Gosub("SIP/109-00000070", "dynamic-routing-out,s,1(109,901200345,ANSWER,4)") in new stack
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@dynamic-routing-out:1] NoOp("SIP/109-00000070", "Test if save on dynamic routing list") in new stack
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@dynamic-routing-out:2] Set("SIP/109-00000070", "CALLER=109") in new stack
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@dynamic-routing-out:3] Set("SIP/109-00000070", "CALLED=901200345") in new stack
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@dynamic-routing-out:4] Set("SIP/109-00000070", "STATUS=ANSWER") in new stack
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@dynamic-routing-out:5] Set("SIP/109-00000070", "TRKID=4") in new stack
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@dynamic-routing-out:6] Set("SIP/109-00000070", "ALLOW_DR=no") in new stack
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@dynamic-routing-out:7] Set("SIP/109-00000070", "DYNROUTING_OMC=yes") in new stack
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@dynamic-routing-out:8] GotoIf("SIP/109-00000070", "0?:comeback") in new stack
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx_builtins.c: Goto (dynamic-routing-out,s,10)
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Executing [s@dynamic-routing-out:10] Return("SIP/109-00000070", "") in new stack
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Executing [h@trk-4-dial:2] Hangup("SIP/109-00000070", "") in new stack
    [2020-07-08 16:18:54] VERBOSE[18970][C-0000003c] pbx.c: Spawn extension (trk-4-dial, h, 2) exited non-zero on 'SIP/109-00000070'

    0