Hi @miguel
After update config ext 101 & 102, but 101 still not hear busy tone, 101 only hear tone ringing tone / progress tone.
asterisk log
[2022-10-27 23:10:46] VERBOSE[1606] res_rtp_asterisk.c: DTLS ECDH initialized (automatic), faster PFS enabled
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [081287264002@cos-all:1] NoOp("PJSIP/102-00000006", "More than on digit pattern") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [081287264002@cos-all:2] Gosub("PJSIP/102-00000006", "s,1(081287264002)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:1] Set("PJSIP/102-00000006", "EXTENSION=081287264002") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:2] NoOp("PJSIP/102-00000006", "Dialing 081287264002 from 102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:3] Gosub("PJSIP/102-00000006", "sub-set-global-vars,s,1(421915deea377c0d,081287264002,parking-1)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-global-vars:1] NoOp("PJSIP/102-00000006", "Setting Global Vars") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-global-vars:2] Set("PJSIP/102-00000006", "TENANT_PATH=421915deea377c0d") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-global-vars:3] Set("PJSIP/102-00000006", "PREFIX=") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-global-vars:4] Set("PJSIP/102-00000006", "__PICKUPMARK=081287264002") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-global-vars:5] Set("PJSIP/102-00000006", "__PARKINGLOT=parking-1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-global-vars:6] Set("PJSIP/102-00000006", "__DYNAMIC_FEATURES=custom-features") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-global-vars:7] Set("PJSIP/102-00000006", "ICALL=no") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-global-vars:8] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:4] Gosub("PJSIP/102-00000006", "sub-set-call-vars,s,1(421915deea377c0d,081287264002,1,cos-all,ARS-all)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:1] NoOp("PJSIP/102-00000006", "Setting Call Vars") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:2] Set("PJSIP/102-00000006", "_TENANT=421915deea377c0d") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:3] Set("PJSIP/102-00000006", "_CALL_DESTINATION=081287264002") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:4] Set("PJSIP/102-00000006", "_COS_ID=1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:5] Set("PJSIP/102-00000006", "_COS_NAME=cos-all") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:6] Set("PJSIP/102-00000006", "__TRANSFER_CONTEXT=cos-all") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:7] Set("PJSIP/102-00000006", "_ARS=ARS-all") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:8] Set("PJSIP/102-00000006", "CDR(tenant)=vitalpbx") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:9] Set("PJSIP/102-00000006", "CDR(destination)=081287264002") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:10] Set("PJSIP/102-00000006", "CDR(trunk)=") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:11] Set("PJSIP/102-00000006", "CDR(did)=") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:12] Set("PJSIP/102-00000006", "__CALLID=1666887046.10") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:13] ExecIf("PJSIP/102-00000006", "0?Set(CHANNEL(language)=)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:14] ExecIf("PJSIP/102-00000006", "0?Set(CHANNEL(language)=)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-set-call-vars:15] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:5] Gosub("PJSIP/102-00000006", "sub-construct-cid,s,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:1] NoOp("PJSIP/102-00000006", "Building CID") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:2] Set("PJSIP/102-00000006", "_CALL_SOURCE=102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:3] Set("PJSIP/102-00000006", "_CS=102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:4] GotoIf("PJSIP/102-00000006", "0?return") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:5] Gosub("PJSIP/102-00000006", "sub-get-device-tree,s,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-get-device-tree:1] NoOp("PJSIP/102-00000006", "Configuring Device Tree") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-get-device-tree:2] Set("PJSIP/102-00000006", "DEV_TECHNOLOGY=PJSIP") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-get-device-tree:3] GosubIf("PJSIP/102-00000006", "0?s-local,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-get-device-tree:4] GotoIf("PJSIP/102-00000006", "0?return") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-get-device-tree:5] GotoIf("PJSIP/102-00000006", "1?pjsip") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (sub-get-device-tree,s,9)
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-get-device-tree:9] NoOp("PJSIP/102-00000006", "A PJSIP") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-get-device-tree:10] Set("PJSIP/102-00000006", "DEV_USER=102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-get-device-tree:11] Goto("PJSIP/102-00000006", "build_interface") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (sub-get-device-tree,s,13)
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-get-device-tree:13] Set("PJSIP/102-00000006", "_INTERFACE=PJSIP/102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-get-device-tree:14] Set("PJSIP/102-00000006", "DEV_TREE=devices/PJSIP/102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-get-device-tree:15] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:6] GotoIf("PJSIP/102-00000006", "0?return") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:7] Set("PJSIP/102-00000006", "_CALL_SOURCE=102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:8] Set("PJSIP/102-00000006", "_CS=102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:9] ExecIf("PJSIP/102-00000006", "1?Set(CALLERID(all)="102" <102>)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:10] Set("PJSIP/102-00000006", "_CID_CONSTRUCTED=yes") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:11] NoOp("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:12] Set("PJSIP/102-00000006", "CDR(source)=102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-construct-cid:13] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:6] Gosub("PJSIP/102-00000006", "set-global-tenant-vars,s,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@set-global-tenant-vars:1] NoOp("PJSIP/102-00000006", "Setting Global Vars for vitalpbx Tenant") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@set-global-tenant-vars:2] Set("PJSIP/102-00000006", "__TENANT_PATH=421915deea377c0d") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@set-global-tenant-vars:3] Set("PJSIP/102-00000006", "__TENANT_PREFIX=") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@set-global-tenant-vars:4] Set("PJSIP/102-00000006", "__QUEUE_AGENTS_CONTEXT=queue-call-to-agents") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@set-global-tenant-vars:5] Set("PJSIP/102-00000006", "__FOLLOWME_CONTEXT=ext-followme") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@set-global-tenant-vars:6] Set("PJSIP/102-00000006", "__HINTS_CONTEXT=extension-hints") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@set-global-tenant-vars:7] Set("PJSIP/102-00000006", "__DEFAULT_COS=cos-all") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@set-global-tenant-vars:8] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:7] GotoIf("PJSIP/102-00000006", "0?local-dialing") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:8] NoOp("PJSIP/102-00000006", "Check if is an Emergency Call") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:9] GotoIf("PJSIP/102-00000006", "0?emergency-calls,081287264002,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:10] Gosub("PJSIP/102-00000006", "sub-lockphone-check,s,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-lockphone-check:1] NoOp("PJSIP/102-00000006", "SUB: LOCK PHONE CHECK") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-lockphone-check:2] GotoIf("PJSIP/102-00000006", "0?unlock") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-lockphone-check:3] GotoIf("PJSIP/102-00000006", "0?unlock") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-lockphone-check:4] GotoIf("PJSIP/102-00000006", "0?unlock") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-lockphone-check:5] GotoIf("PJSIP/102-00000006", "0?unlock") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-lockphone-check:6] GotoIf("PJSIP/102-00000006", "0?:unlock") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (sub-lockphone-check,s,12)
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-lockphone-check:12] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:11] Gosub("PJSIP/102-00000006", "sub-local-dialing,s,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-local-dialing:1] NoOp("PJSIP/102-00000006", "Trying to determine if is a local extension") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-local-dialing:2] GotoIf("PJSIP/102-00000006", "0?:return") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (sub-local-dialing,s,5)
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-local-dialing:5] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:12] GotoIf("PJSIP/102-00000006", "0?end-call") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@cos-all:13] Goto("PJSIP/102-00000006", "cos-all-post,081287264002,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (cos-all-post,081287264002,1)
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [081287264002@cos-all-post:1] NoOp("PJSIP/102-00000006", "Outbound Route: OCDEMO") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [081287264002@cos-all-post:2] Gosub("PJSIP/102-00000006", "sub-setup-call-type,s,1(outgoing)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("PJSIP/102-00000006", "Determinating Call Type") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("PJSIP/102-00000006", "0?return") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("PJSIP/102-00000006", "s-outgoing,1()") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("PJSIP/102-00000006", "Outgoing Call") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("PJSIP/102-00000006", "__CALL_TYPE=3") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("PJSIP/102-00000006", "__CALL_TYPE_LABEL=OUT") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("PJSIP/102-00000006", "0?s-transit,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:4] Set("PJSIP/102-00000006", "__CALL_TYPE_CONFIGURED=yes") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:5] Set("PJSIP/102-00000006", "CDR(calltype)=3") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:6] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [081287264002@cos-all-post:3] Set("PJSIP/102-00000006", "DNID=2203081287264002") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [081287264002@cos-all-post:4] NoOp("PJSIP/102-00000006", "Start to Dial Trunks") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [081287264002@cos-all-post:5] Goto("PJSIP/102-00000006", "s-1,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (cos-all-post,s-1,1)
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-1@cos-all-post:1] Set("PJSIP/102-00000006", "__CONFIGURED_BY_TENANT=yes") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-1@cos-all-post:2] Gosub("PJSIP/102-00000006", "sub-construct-cid,s-external,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:1] NoOp("PJSIP/102-00000006", "Setting External CID") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:2] Set("PJSIP/102-00000006", "EXTENSION_NUMBER=102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:3] GotoIf("PJSIP/102-00000006", "0?return") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:4] GotoIf("PJSIP/102-00000006", "0?return") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:5] Set("PJSIP/102-00000006", "EMERGENCY_CALL=no") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:6] Set("PJSIP/102-00000006", "EXTENSION_EMERGENCY_CID=") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:7] Set("PJSIP/102-00000006", "DYNAMIC_EXTERNAL_CID=no") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:8] ExecIf("PJSIP/102-00000006", "1?Set(EXTENSION_EMERGENCY_CID=)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:9] ExecIf("PJSIP/102-00000006", "1?Set(DYNAMIC_EXTERNAL_CID=no)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:10] GotoIf("PJSIP/102-00000006", "0?check-emergency") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:11] Set("PJSIP/102-00000006", "EXTENSION_EXTERNAL_CID=") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:12] ExecIf("PJSIP/102-00000006", "1?Set(CALLERID(all)=)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:13] GotoIf("PJSIP/102-00000006", "1?:check-emergency") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:14] Set("PJSIP/102-00000006", "TENANT_DEFAULT_EXTERNAL_CID=") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:15] ExecIf("PJSIP/102-00000006", "0?Set(CALLERID(all)=)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:16] GotoIf("PJSIP/102-00000006", "0?:info") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (sub-construct-cid,s-external,30)
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:30] NoOp("PJSIP/102-00000006", "CID Name: , CID Number: ") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:31] Set("PJSIP/102-00000006", "SEND_CID=yes") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:32] ExecIf("PJSIP/102-00000006", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:33] ExecIf("PJSIP/102-00000006", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:34] Set("PJSIP/102-00000006", "__EXT_CID_CONSTRUCTED=yes") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:35] Set("PJSIP/102-00000006", "CDR(source)=102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-external@sub-construct-cid:36] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-1@cos-all-post:3] Gosub("PJSIP/102-00000006", "trk-1,2203081287264002,1(from-trk-grp)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1:1] Set("PJSIP/102-00000006", "_NTD=2203081287264002") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1:2] Gosub("PJSIP/102-00000006", "trk-1-dial,2203081287264002,1(from-trk-grp)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:1] NoOp("PJSIP/102-00000006", "Trunk: DEMO") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:2] NoOp("PJSIP/102-00000006", "Dialing: 2203081287264002") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:3] Set("PJSIP/102-00000006", "CDR(trunk)=1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:4] Set("PJSIP/102-00000006", "SKIP_HANGUP_AUDIOS=no") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:5] Gosub("PJSIP/102-00000006", "sub-setup-call-type,s,1(outgoing,no)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("PJSIP/102-00000006", "Determinating Call Type") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("PJSIP/102-00000006", "0?return") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("PJSIP/102-00000006", "s-outgoing,1(no)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("PJSIP/102-00000006", "Outgoing Call") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("PJSIP/102-00000006", "__CALL_TYPE=3") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("PJSIP/102-00000006", "__CALL_TYPE_LABEL=OUT") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("PJSIP/102-00000006", "0?s-transit,1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:4] Set("PJSIP/102-00000006", "__CALL_TYPE_CONFIGURED=yes") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:5] Set("PJSIP/102-00000006", "CDR(calltype)=3") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-setup-call-type:6] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:6] ExecIf("PJSIP/102-00000006", "0?Set(CALLERID(all)=):") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:7] GotoIf("PJSIP/102-00000006", "1?:after-val-sim-calls") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:8] Set("PJSIP/102-00000006", "MAX_TENANT_CALLS=0") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:9] Set("PJSIP/102-00000006", "TENANT_NAME=vitalpbx") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:10] GotoIf("PJSIP/102-00000006", "0?:after-val-sim-calls") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (trk-1-dial,2203081287264002,18)
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:18] NoOp("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:19] Set("PJSIP/102-00000006", "MAX_CALLS=2") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:20] NoOp("PJSIP/102-00000006", "Allowed Outgoing Simultaneous Calls: 2") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:21] Set("PJSIP/102-00000006", "GROUP()=trk-1") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:22] GotoIf("PJSIP/102-00000006", "0?:trk-conf") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (trk-1-dial,2203081287264002,26)
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:26] NoOp("PJSIP/102-00000006", "Configuring Trunk Parameters") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:27] ExecIf("PJSIP/102-00000006", "1?Set(CHANNEL(musicclass)=default):") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:28] Set("PJSIP/102-00000006", "__TRUNK_MOH_SET=yes") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:29] Set("PJSIP/102-00000006", "DIAL_PREFIX=") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:30] Set("PJSIP/102-00000006", "__TRKDIALOPTIONS=kKt") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:31] Set("PJSIP/102-00000006", "ORIGINATOR=102") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:32] Set("PJSIP/102-00000006", "RING_TIME=90") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:33] Set("PJSIP/102-00000006", "CALLED=2203081287264002") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:34] Set("PJSIP/102-00000006", "TRUNK_ENDPOINT=DEMO") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:35] NoOp("PJSIP/102-00000006", "Force Recording by Trunk") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:36] Set("PJSIP/102-00000006", "APP_RECORDING=yes") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:37] Set("PJSIP/102-00000006", "__CIDNUM=") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:38] Set("PJSIP/102-00000006", "__CIDNAME=") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:39] Set("PJSIP/102-00000006", "FORCE_RECORDING=yes") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:40] Set("PJSIP/102-00000006", "FILTERED_NUMBER=2203081287264002") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:41] GotoIf("PJSIP/102-00000006", "0?:post-fax") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (trk-1-dial,2203081287264002,44)
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:44] NoOp("PJSIP/102-00000006", "End Fax Section") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] pbx.c: Executing [2203081287264002@trk-1-dial:45] Dial("PJSIP/102-00000006", "PJSIP/2203081287264002@DEMO,90,kKtU(sub-before-bridging-call^421915deea377c0d^102^2203081287264002^yes)") in new stack
[2022-10-27 23:10:46] VERBOSE[2646][C-00000004] app_dial.c: Called PJSIP/2203081287264002@DEMO
[2022-10-27 23:10:48] VERBOSE[2646][C-00000004] app_dial.c: PJSIP/DEMO-00000007 is making progress passing it to PJSIP/102-00000006
[2022-10-27 23:10:48] VERBOSE[2646][C-00000004] app_dial.c: PJSIP/102-00000006 requested media update control 26, passing it to PJSIP/DEMO-00000007
[2022-10-27 23:10:48] VERBOSE[2646][C-00000004] app_dial.c: PJSIP/DEMO-00000007 is making progress passing it to PJSIP/102-00000006
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] app_dial.c: PJSIP/DEMO-00000007 answered PJSIP/102-00000006
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] app_stack.c: PJSIP/DEMO-00000007 Internal Gosub(sub-before-bridging-call,s,1(421915deea377c0d,102,2203081287264002,yes)) start
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:1] NoOp("PJSIP/DEMO-00000007", "Executing sub-routine before connecting to the calling channel") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:2] Set("PJSIP/DEMO-00000007", "TENANT=421915deea377c0d") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:3] Set("PJSIP/DEMO-00000007", "CALLER=102") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:4] Set("PJSIP/DEMO-00000007", "CALLEE=2203081287264002") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:5] Set("PJSIP/DEMO-00000007", "FORCE_CALL_RECORDING=yes") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:6] NoOp("PJSIP/DEMO-00000007", "Check if the follow-me confirmation sub-routine needs to be executed") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:7] GotoIf("PJSIP/DEMO-00000007", "1?call-recording") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (sub-before-bridging-call,s,9)
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:9] NoOp("PJSIP/DEMO-00000007", "Executing the call-recording sub-routine") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:10] Gosub("PJSIP/DEMO-00000007", "sub-call-recording,s,1(421915deea377c0d,102,2203081287264002,yes)") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-call-recording:1] NoOp("PJSIP/DEMO-00000007", "Check if call will be recorded on tenant 421915deea377c0d") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-call-recording:2] NoOp("PJSIP/DEMO-00000007", "Call Type: 3 ") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-call-recording:3] Set("PJSIP/DEMO-00000007", "TENANT=421915deea377c0d") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-call-recording:4] Set("PJSIP/DEMO-00000007", "CALLER=102") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-call-recording:5] Set("PJSIP/DEMO-00000007", "CALLEE=2203081287264002") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-call-recording:6] Set("PJSIP/DEMO-00000007", "APP_FORCE=yes") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-call-recording:7] GotoIf("PJSIP/DEMO-00000007", "1?:rec_no_available") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-call-recording:8] GotoIf("PJSIP/DEMO-00000007", "1?perform-recording") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (sub-call-recording,s,14)
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-call-recording:14] Gosub("PJSIP/DEMO-00000007", "sub-perform-recording,s,1") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-perform-recording:1] NoOp("PJSIP/DEMO-00000007", "Perform Call Recording") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-perform-recording:2] Set("PJSIP/DEMO-00000007", "RECORDING_ID=1666887046.11") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-perform-recording:3] Set("PJSIP/DEMO-00000007", "APP_TYPE=NONE") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-perform-recording:4] Set("PJSIP/DEMO-00000007", "AUDIO_FILE=231053-OUT-NONE-102-081287264002-1666887046.11") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-perform-recording:5] Set("PJSIP/DEMO-00000007", "RECORDING_FORMAT=wav") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-perform-recording:6] Set("PJSIP/DEMO-00000007", "__REC_FILENAME=/var/spool/asterisk/monitor/2022/10/27/231053-OUT-NONE-102-081287264002-1666887046.11.wav") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-perform-recording:7] GotoIf("PJSIP/DEMO-00000007", "0?:set_rec_file") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (sub-perform-recording,s,10)
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-perform-recording:10] Set("PJSIP/DEMO-00000007", "CDR(recfile)=/var/spool/asterisk/monitor/2022/10/27/231053-OUT-NONE-102-081287264002-1666887046.11.wav") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-perform-recording:11] MixMonitor("PJSIP/DEMO-00000007", "/var/spool/asterisk/monitor/2022/10/27/231053-OUT-NONE-102-081287264002-1666887046.11.wav,b,") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-perform-recording:12] Return("PJSIP/DEMO-00000007", "") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-call-recording:15] Goto("PJSIP/DEMO-00000007", "return") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (sub-call-recording,s,17)
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-call-recording:17] Return("PJSIP/DEMO-00000007", "") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:11] NoOp("PJSIP/DEMO-00000007", "Check if there's a custom sub-routine defined for executing it") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:12] GosubIf("PJSIP/DEMO-00000007", "0?before-bridging-call-hook,s,1") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] pbx.c: Executing [s@sub-before-bridging-call:13] Return("PJSIP/DEMO-00000007", "") in new stack
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] app_stack.c: Spawn extension (trk-1-in, , 1) exited non-zero on 'PJSIP/DEMO-00000007'
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] app_stack.c: PJSIP/DEMO-00000007 Internal Gosub(sub-before-bridging-call,s,1(421915deea377c0d,102,2203081287264002,yes)) complete GOSUB_RETVAL=
[2022-10-27 23:10:53] VERBOSE[2655][C-00000004] bridge_channel.c: Channel PJSIP/DEMO-00000007 joined 'simple_bridge' basic-bridge <9b0f5c9f-8cdd-4be4-9faa-4c1ff1698a80>
[2022-10-27 23:10:53] VERBOSE[2646][C-00000004] bridge_channel.c: Channel PJSIP/102-00000006 joined 'simple_bridge' basic-bridge <9b0f5c9f-8cdd-4be4-9faa-4c1ff1698a80>
[2022-10-27 23:10:53] VERBOSE[2654][C-00000004] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/DEMO-00000007
[2022-10-27 23:11:41] VERBOSE[1606] res_rtp_asterisk.c: DTLS ECDH initialized (automatic), faster PFS enabled
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@cos-all:1] NoOp("PJSIP/101-00000008", "More than on digit pattern") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@cos-all:2] Gosub("PJSIP/101-00000008", "s,1(102)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@cos-all:1] Set("PJSIP/101-00000008", "EXTENSION=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@cos-all:2] NoOp("PJSIP/101-00000008", "Dialing 102 from 101") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@cos-all:3] Gosub("PJSIP/101-00000008", "sub-set-global-vars,s,1(421915deea377c0d,102,parking-1)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-global-vars:1] NoOp("PJSIP/101-00000008", "Setting Global Vars") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-global-vars:2] Set("PJSIP/101-00000008", "TENANT_PATH=421915deea377c0d") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-global-vars:3] Set("PJSIP/101-00000008", "PREFIX=") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-global-vars:4] Set("PJSIP/101-00000008", "__PICKUPMARK=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-global-vars:5] Set("PJSIP/101-00000008", "__PARKINGLOT=parking-1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-global-vars:6] Set("PJSIP/101-00000008", "__DYNAMIC_FEATURES=custom-features") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-global-vars:7] Set("PJSIP/101-00000008", "ICALL=no") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-global-vars:8] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@cos-all:4] Gosub("PJSIP/101-00000008", "sub-set-call-vars,s,1(421915deea377c0d,102,1,cos-all,ARS-all)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:1] NoOp("PJSIP/101-00000008", "Setting Call Vars") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:2] Set("PJSIP/101-00000008", "_TENANT=421915deea377c0d") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:3] Set("PJSIP/101-00000008", "_CALL_DESTINATION=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:4] Set("PJSIP/101-00000008", "_COS_ID=1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:5] Set("PJSIP/101-00000008", "_COS_NAME=cos-all") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:6] Set("PJSIP/101-00000008", "__TRANSFER_CONTEXT=cos-all") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:7] Set("PJSIP/101-00000008", "_ARS=ARS-all") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:8] Set("PJSIP/101-00000008", "CDR(tenant)=vitalpbx") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:9] Set("PJSIP/101-00000008", "CDR(destination)=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:10] Set("PJSIP/101-00000008", "CDR(trunk)=") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:11] Set("PJSIP/101-00000008", "CDR(did)=") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:12] Set("PJSIP/101-00000008", "__CALLID=1666887101.12") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:13] ExecIf("PJSIP/101-00000008", "0?Set(CHANNEL(language)=)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:14] ExecIf("PJSIP/101-00000008", "0?Set(CHANNEL(language)=)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-set-call-vars:15] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@cos-all:5] Gosub("PJSIP/101-00000008", "sub-construct-cid,s,1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:1] NoOp("PJSIP/101-00000008", "Building CID") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:2] Set("PJSIP/101-00000008", "_CALL_SOURCE=101") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:3] Set("PJSIP/101-00000008", "_CS=101") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:4] GotoIf("PJSIP/101-00000008", "0?return") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:5] Gosub("PJSIP/101-00000008", "sub-get-device-tree,s,1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-get-device-tree:1] NoOp("PJSIP/101-00000008", "Configuring Device Tree") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-get-device-tree:2] Set("PJSIP/101-00000008", "DEV_TECHNOLOGY=PJSIP") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-get-device-tree:3] GosubIf("PJSIP/101-00000008", "0?s-local,1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-get-device-tree:4] GotoIf("PJSIP/101-00000008", "0?return") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-get-device-tree:5] GotoIf("PJSIP/101-00000008", "1?pjsip") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-get-device-tree,s,9)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-get-device-tree:9] NoOp("PJSIP/101-00000008", "A PJSIP") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-get-device-tree:10] Set("PJSIP/101-00000008", "DEV_USER=101") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-get-device-tree:11] Goto("PJSIP/101-00000008", "build_interface") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-get-device-tree,s,13)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-get-device-tree:13] Set("PJSIP/101-00000008", "_INTERFACE=PJSIP/101") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-get-device-tree:14] Set("PJSIP/101-00000008", "DEV_TREE=devices/PJSIP/101") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-get-device-tree:15] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:6] GotoIf("PJSIP/101-00000008", "0?return") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:7] Set("PJSIP/101-00000008", "_CALL_SOURCE=101") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:8] Set("PJSIP/101-00000008", "_CS=101") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:9] ExecIf("PJSIP/101-00000008", "1?Set(CALLERID(all)="101" <101>)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:10] Set("PJSIP/101-00000008", "_CID_CONSTRUCTED=yes") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:11] NoOp("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:12] Set("PJSIP/101-00000008", "CDR(source)=101") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-construct-cid:13] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@cos-all:6] Gosub("PJSIP/101-00000008", "set-global-tenant-vars,s,1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@set-global-tenant-vars:1] NoOp("PJSIP/101-00000008", "Setting Global Vars for vitalpbx Tenant") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@set-global-tenant-vars:2] Set("PJSIP/101-00000008", "__TENANT_PATH=421915deea377c0d") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@set-global-tenant-vars:3] Set("PJSIP/101-00000008", "__TENANT_PREFIX=") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@set-global-tenant-vars:4] Set("PJSIP/101-00000008", "__QUEUE_AGENTS_CONTEXT=queue-call-to-agents") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@set-global-tenant-vars:5] Set("PJSIP/101-00000008", "__FOLLOWME_CONTEXT=ext-followme") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@set-global-tenant-vars:6] Set("PJSIP/101-00000008", "__HINTS_CONTEXT=extension-hints") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@set-global-tenant-vars:7] Set("PJSIP/101-00000008", "__DEFAULT_COS=cos-all") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@set-global-tenant-vars:8] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@cos-all:7] GotoIf("PJSIP/101-00000008", "0?local-dialing") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@cos-all:8] NoOp("PJSIP/101-00000008", "Check if is an Emergency Call") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@cos-all:9] GotoIf("PJSIP/101-00000008", "0?emergency-calls,102,1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@cos-all:10] Gosub("PJSIP/101-00000008", "sub-lockphone-check,s,1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-lockphone-check:1] NoOp("PJSIP/101-00000008", "SUB: LOCK PHONE CHECK") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-lockphone-check:2] GotoIf("PJSIP/101-00000008", "0?unlock") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-lockphone-check:3] GotoIf("PJSIP/101-00000008", "0?unlock") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-lockphone-check:4] GotoIf("PJSIP/101-00000008", "0?unlock") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-lockphone-check:5] GotoIf("PJSIP/101-00000008", "0?unlock") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-lockphone-check:6] GotoIf("PJSIP/101-00000008", "0?:unlock") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-lockphone-check,s,12)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-lockphone-check:12] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@cos-all:11] Gosub("PJSIP/101-00000008", "sub-local-dialing,s,1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-local-dialing:1] NoOp("PJSIP/101-00000008", "Trying to determine if is a local extension") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-local-dialing:2] GotoIf("PJSIP/101-00000008", "1?:return") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-local-dialing:3] Set("PJSIP/101-00000008", "EXTENSION_TO_DIAL=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-local-dialing:4] Goto("PJSIP/101-00000008", "102,1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-local-dialing,102,1)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:1] NoOp("PJSIP/101-00000008", "Dialing Local Extension") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:2] ExecIf("PJSIP/101-00000008", "0?NoCDR()") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:3] Gosub("PJSIP/101-00000008", "sub-setup-call-type,s,1(internal)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("PJSIP/101-00000008", "Determinating Call Type") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("PJSIP/101-00000008", "0?return") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("PJSIP/101-00000008", "s-internal,1()") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s-internal@sub-setup-call-type:1] NoOp("PJSIP/101-00000008", "Internal Call") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s-internal@sub-setup-call-type:2] Set("PJSIP/101-00000008", "__CALL_TYPE=1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s-internal@sub-setup-call-type:3] Set("PJSIP/101-00000008", "__CALL_TYPE_LABEL=LOCAL") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s-internal@sub-setup-call-type:4] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-setup-call-type:4] Set("PJSIP/101-00000008", "__CALL_TYPE_CONFIGURED=yes") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-setup-call-type:5] Set("PJSIP/101-00000008", "CDR(calltype)=1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-setup-call-type:6] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:4] Set("PJSIP/101-00000008", "_DESTINATION_STATE=INUSE") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:5] Set("PJSIP/101-00000008", "_DESTINATION_NUMBER=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:6] Set("PJSIP/101-00000008", "_DESTINATION_COS_NAME=cos-all") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:7] Gosub("PJSIP/101-00000008", "sub-check-cos-privacy,s,1(cos-all,1,cos-all)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-cos-privacy:1] NoOp("PJSIP/101-00000008", "Checking Privacy of CoS: cos-all") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-cos-privacy:2] GotoIf("PJSIP/101-00000008", "1?:no_local") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-cos-privacy:3] Set("PJSIP/101-00000008", "CALLER_COS_ID=1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-cos-privacy:4] Set("PJSIP/101-00000008", "CALLER_COS_NAME=cos-all") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-cos-privacy:5] Set("PJSIP/101-00000008", "CALLED_COS_ID=1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-cos-privacy:6] GotoIf("PJSIP/101-00000008", "1?same_cos") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-check-cos-privacy,s,17)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-cos-privacy:17] NoOp("PJSIP/101-00000008", "The caller CoS and Callee CoS are the same") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-cos-privacy:18] Goto("PJSIP/101-00000008", "return") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-check-cos-privacy,s,22)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-cos-privacy:22] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:8] Set("PJSIP/101-00000008", "DIAL_STRING=PJSIP/102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:9] Set("PJSIP/101-00000008", "DB(421915deea377c0d/LASTCALLER/102)=101") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:10] Set("PJSIP/101-00000008", "IGNORE_DIVERSIONS=") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:11] Set("PJSIP/101-00000008", "QUEUE_CALL=") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:12] GosubIf("PJSIP/101-00000008", "0?sub-queue-members,s,1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:13] GosubIf("PJSIP/101-00000008", "0?:sub-check-diversions,s,1(predialing)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:1] NoOp("PJSIP/101-00000008", "Checking diversions for extension 102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:2] Set("PJSIP/101-00000008", "OLD_CALLER_ID="101" <101>") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:3] GosubIf("PJSIP/101-00000008", "0?:return") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:9] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:4] ExecIf("PJSIP/101-00000008", "0?Set(CALLERID(all)="102" <102>)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:5] Set("PJSIP/101-00000008", "__CALL_ORIGIN=diversions") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:6] Set("PJSIP/101-00000008", "__DIVERSION_ORIGIN=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:7] Gosub("PJSIP/101-00000008", "s-predialing,1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s-predialing@sub-check-diversions:1] NoOp("PJSIP/101-00000008", "Diversions on pre-dialing") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s-predialing@sub-check-diversions:2] GosubIf("PJSIP/101-00000008", "0?:diversion-testing,1(Do not Disturb,DND,,,dnd)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:1] NoOp("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:2] Set("PJSIP/101-00000008", "FROM_NUMBER=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:3] GotoIf("PJSIP/101-00000008", "0?:next-diversion") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-check-diversions,diversion-testing,36)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:36] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s-predialing@sub-check-diversions:3] Gosub("PJSIP/101-00000008", "diversion-testing,1(Call Forward Immeditely,CFI,,,toggle_cfi)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:1] NoOp("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:2] Set("PJSIP/101-00000008", "FROM_NUMBER=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:3] GotoIf("PJSIP/101-00000008", "0?:next-diversion") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-check-diversions,diversion-testing,36)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:36] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s-predialing@sub-check-diversions:4] GosubIf("PJSIP/101-00000008", "0?:diversion-testing,1(Boss Secretray,BOSS,,,boss_secretary)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:1] NoOp("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:2] Set("PJSIP/101-00000008", "FROM_NUMBER=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:3] GotoIf("PJSIP/101-00000008", "0?:next-diversion") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-check-diversions,diversion-testing,36)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:36] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s-predialing@sub-check-diversions:5] GosubIf("PJSIP/101-00000008", "0?:diversion-testing,1(Personal Assistant,PEA,sub-personal-assistant,s,1)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:1] NoOp("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:2] Set("PJSIP/101-00000008", "FROM_NUMBER=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:3] GotoIf("PJSIP/101-00000008", "0?:next-diversion") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-check-diversions,diversion-testing,36)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:36] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s-predialing@sub-check-diversions:6] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:8] Set("PJSIP/101-00000008", "CALLERID(all)="101" <101>") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:9] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:14] GotoIf("PJSIP/101-00000008", "0?post-dial") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:15] Gosub("PJSIP/101-00000008", "parse-dial-string,s,1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:1] NoOp("PJSIP/101-00000008", "Parsing dial string: PJSIP/102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:2] Set("PJSIP/101-00000008", "NEW_DIAL_STRING=") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:3] Set("PJSIP/101-00000008", "COUNTER=1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:4] Set("PJSIP/101-00000008", "CURRENT_DEVICE=PJSIP/102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:5] While("PJSIP/101-00000008", "1") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:6] Set("PJSIP/101-00000008", "TECHNOLOGY=PJSIP") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:7] Set("PJSIP/101-00000008", "USER=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:8] GotoIf("PJSIP/101-00000008", "1?:regular") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:9] Goto("PJSIP/101-00000008", "regular-pjsip") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (parse-dial-string,s,12)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:12] Set("PJSIP/101-00000008", "PARSED_DEV=PJSIP/102/sip:139.192.45.67:64037;transport=ws") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:13] ExecIf("PJSIP/101-00000008", "0?Set(PARSED_DEV=PJSIP/102)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:14] Goto("PJSIP/101-00000008", "append") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (parse-dial-string,s,16)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:16] GotoIf("PJSIP/101-00000008", "1?:concatenate") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:17] Set("PJSIP/101-00000008", "NEW_DIAL_STRING=PJSIP/102/sip:139.192.45.67:64037;transport=ws") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:18] Goto("PJSIP/101-00000008", "next") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (parse-dial-string,s,21)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:21] Set("PJSIP/101-00000008", "COUNTER=2") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:22] Set("PJSIP/101-00000008", "CURRENT_DEVICE=") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:23] EndWhile("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:5] While("PJSIP/101-00000008", "0") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:24] NoOp("PJSIP/101-00000008", "New dialstring: PJSIP/102/sip:139.192.45.67:64037;transport=ws") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:25] Set("PJSIP/101-00000008", "DIAL_STRING=PJSIP/102/sip:139.192.45.67:64037;transport=ws") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@parse-dial-string:26] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:16] NoOp("PJSIP/101-00000008", "Configuring Dial Settings") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:17] Set("PJSIP/101-00000008", "RING_TIME=30") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:18] Set("PJSIP/101-00000008", "RING_TIME=30") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:19] Set("PJSIP/101-00000008", "RING_TIME=30") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:20] Set("PJSIP/101-00000008", "DIAL_OPTIONS=kKt") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:21] Set("PJSIP/101-00000008", "DIAL_OPTIONS=kKt") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:22] GotoIf("PJSIP/101-00000008", "0?:answermode") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-local-dialing,102,26)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:26] Set("PJSIP/101-00000008", "ANSWER_MODE=disable") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:27] Set("PJSIP/101-00000008", "ANSWER_MODE=disable") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:28] GotoIf("PJSIP/101-00000008", "0?check-hd") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:29] ExecIf("PJSIP/101-00000008", "0?Set(EXEC_AA=yes)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:30] ExecIf("PJSIP/101-00000008", "0?Set(EXEC_AA=yes)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:31] Set("PJSIP/101-00000008", "IS_HOT_DESK=no") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:32] Set("PJSIP/101-00000008", "MOH_CLASS=default") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:33] GosubIf("PJSIP/101-00000008", "0?sub-set-moh,s,1(default)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:34] GotoIf("PJSIP/101-00000008", "0?busy-here") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:35] Set("PJSIP/101-00000008", "FORCE_RECORDING=no") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:36] Set("PJSIP/101-00000008", "DIAL_OPTIONS=kKtb(sub-before-connecting-call^s^1(421915deea377c0d^101^102^^))") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:37] Set("PJSIP/101-00000008", "DIAL_OPTIONS=kKtb(sub-before-connecting-call^s^1(421915deea377c0d^101^102^^))U(sub-before-bridging-call^421915deea377c0d^101^102^no)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:38] Set("PJSIP/101-00000008", "DIAL_OPTIONS=kKtb(sub-befor(callwaiting)e-connecting-call^s^1(421915deea377c0d^101^102^^))U(sub-before-bridging-call^421915deea377c0d^101^102^no)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:39] Set("PJSIP/101-00000008", "__TRANSFERED_CALL=TRUE") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:40] NoOp("PJSIP/101-00000008", "Enable Follow-Me Ring Time If needed") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:41] GotoIf("PJSIP/101-00000008", "0?:check-rt") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-local-dialing,102,48)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:48] GotoIf("PJSIP/101-00000008", "0?post-dial") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:49] GotoIf("PJSIP/101-00000008", "0?end_call") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:50] Gosub("PJSIP/101-00000008", "sub-udpate-virtual-dev-state,s,1(102,BUSY)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:1] NoOp("PJSIP/101-00000008", "Update Virtual Devices State") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:2] Set("PJSIP/101-00000008", "EXT_NUM=102") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:3] Set("PJSIP/101-00000008", "NEW_STATUS=BUSY") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:4] Set("PJSIP/101-00000008", "EXTENSION_ID=2") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:5] Set("PJSIP/101-00000008", "ODBC_ID=") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:6] GotoIf("PJSIP/101-00000008", "1?no_rows") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-udpate-virtual-dev-state,s,14)
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:14] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:51] Dial("PJSIP/101-00000008", "PJSIP/102/sip:139.192.45.67:64037;transport=ws,30,kKtb(sub-befor(callwaiting)e-connecting-call^s^1(421915deea377c0d^101^102^^))U(sub-before-bridging-call^421915deea377c0d^101^102^no)") in new stack
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] app_stack.c: PJSIP/102-00000009 Internal Gosub(sub-local-dialing,102,sub-befor(callwaiting)e-connecting-call,s,1(421915deea377c0d,101,102,,)) start
[2022-10-27 23:11:41] NOTICE[2738][C-00000005] pbx.c: No such label 'sub-befor' in extension '102' in context 'sub-local-dialing'
[2022-10-27 23:11:41] WARNING[2738][C-00000005] pbx.c: Priority 'sub-befor' must be a number > 0, or valid label
[2022-10-27 23:11:41] ERROR[2738][C-00000005] app_stack.c: Gosub address is invalid: 'sub-local-dialing,102,sub-befor(callwaiting)e-connecting-call,s,1(421915deea377c0d,101,102,,)'
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] app_dial.c: Called PJSIP/102/sip:139.192.45.67:64037;transport=ws
[2022-10-27 23:11:41] VERBOSE[1606] res_rtp_asterisk.c: DTLS ECDH initialized (automatic), faster PFS enabled
[2022-10-27 23:11:41] VERBOSE[2738][C-00000005] app_dial.c: PJSIP/102-00000009 is ringing
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] app_dial.c: Nobody picked up in 30000 ms
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:52] Set("PJSIP/101-00000008", "_CALL_STATUS=NOANSWER") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:53] Set("PJSIP/101-00000008", "_CALL_STATUS=BUSY") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:54] Gosub("PJSIP/101-00000008", "sub-udpate-virtual-dev-state,s,1(102,NOT_INUSE)") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:1] NoOp("PJSIP/101-00000008", "Update Virtual Devices State") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:2] Set("PJSIP/101-00000008", "EXT_NUM=102") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:3] Set("PJSIP/101-00000008", "NEW_STATUS=NOT_INUSE") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:4] Set("PJSIP/101-00000008", "EXTENSION_ID=2") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:5] Set("PJSIP/101-00000008", "ODBC_ID=") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:6] GotoIf("PJSIP/101-00000008", "1?no_rows") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-udpate-virtual-dev-state,s,14)
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:14] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:55] Goto("PJSIP/101-00000008", "check-post-diversions") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-local-dialing,102,57)
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:57] GosubIf("PJSIP/101-00000008", "0?:sub-check-diversions,s,1(postdialing)") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:1] NoOp("PJSIP/101-00000008", "Checking diversions for extension 102") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:2] Set("PJSIP/101-00000008", "OLD_CALLER_ID="101" <101>") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:3] GosubIf("PJSIP/101-00000008", "0?:return") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:9] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:4] ExecIf("PJSIP/101-00000008", "0?Set(CALLERID(all)="102" <102>)") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:5] Set("PJSIP/101-00000008", "__CALL_ORIGIN=diversions") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:6] Set("PJSIP/101-00000008", "__DIVERSION_ORIGIN=102") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:7] Gosub("PJSIP/101-00000008", "s-postdialing,1") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s-postdialing@sub-check-diversions:1] NoOp("PJSIP/101-00000008", "Diversions on post-dialing") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s-postdialing@sub-check-diversions:2] Gosub("PJSIP/101-00000008", "diversion-testing,1(Follow Me,FWM,ext-followme,FW102,follow_me)") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:1] NoOp("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:2] Set("PJSIP/101-00000008", "FROM_NUMBER=102") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:3] GotoIf("PJSIP/101-00000008", "0?:next-diversion") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-check-diversions,diversion-testing,36)
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [diversion-testing@sub-check-diversions:36] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s-postdialing@sub-check-diversions:3] GosubIf("PJSIP/101-00000008", "0?call-forward,1(CFU,toggle_cfu)") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s-postdialing@sub-check-diversions:4] GosubIf("PJSIP/101-00000008", "0?call-forward,1(CFU,toggle_cfu)") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s-postdialing@sub-check-diversions:5] GosubIf("PJSIP/101-00000008", "1?call-forward,1(CFB,toggle_cfb)") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [call-forward@sub-check-diversions:1] NoOp("PJSIP/101-00000008", "CALL FOWARDING") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [call-forward@sub-check-diversions:2] Set("PJSIP/101-00000008", "FROM_NUMBER=102") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [call-forward@sub-check-diversions:3] GotoIf("PJSIP/101-00000008", "0?:return") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-check-diversions,call-forward,14)
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [call-forward@sub-check-diversions:14] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s-postdialing@sub-check-diversions:6] GosubIf("PJSIP/101-00000008", "0?call-forward,1(CFN,toggle_cfn)") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s-postdialing@sub-check-diversions:7] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:8] Set("PJSIP/101-00000008", "CALLERID(all)="101" <101>") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-check-diversions:9] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:58] GotoIf("PJSIP/101-00000008", "0?end_call") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:59] GotoIf("PJSIP/101-00000008", "0?end_call") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:60] GotoIf("PJSIP/101-00000008", "0?end_call") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:61] Gosub("PJSIP/101-00000008", "sub-leave-vm,s,1") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-leave-vm:1] NoOp("PJSIP/101-00000008", "Leave Voicemail") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-leave-vm:2] Set("PJSIP/101-00000008", "VM_OPTIONS=b") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-leave-vm:3] Set("PJSIP/101-00000008", "VM_OPTIONS=b") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-leave-vm:4] GotoIf("PJSIP/101-00000008", "0?:vm_disabled") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-leave-vm,s,8)
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-leave-vm:8] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:62] GosubIf("PJSIP/101-00000008", "1?sub-request-cc,s,1") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-request-cc:1] NoOp("PJSIP/101-00000008", "Trying to request call completion") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-request-cc:2] GotoIf("PJSIP/101-00000008", "1?:return") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-request-cc:3] GotoIf("PJSIP/101-00000008", "0?:return") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-request-cc,s,11)
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-request-cc:11] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [102@sub-local-dialing:63] ExecIf("PJSIP/101-00000008", "1?Busy(5)") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Spawn extension (sub-local-dialing, 102, 63) exited non-zero on 'PJSIP/101-00000008'
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [h@sub-local-dialing:1] NoOp("PJSIP/101-00000008", "Finishing the call") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [h@sub-local-dialing:2] Gosub("PJSIP/101-00000008", "sub-udpate-virtual-dev-state,s,1(102,NOT_INUSE)") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:1] NoOp("PJSIP/101-00000008", "Update Virtual Devices State") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:2] Set("PJSIP/101-00000008", "EXT_NUM=102") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:3] Set("PJSIP/101-00000008", "NEW_STATUS=NOT_INUSE") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:4] Set("PJSIP/101-00000008", "EXTENSION_ID=2") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:5] Set("PJSIP/101-00000008", "ODBC_ID=") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:6] GotoIf("PJSIP/101-00000008", "1?no_rows") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-udpate-virtual-dev-state,s,14)
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-udpate-virtual-dev-state:14] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [h@sub-local-dialing:3] GosubIf("PJSIP/101-00000008", "0?dynamic-routing-connected,s,1(NOANSWER,101)") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [h@sub-local-dialing:4] GotoIf("PJSIP/101-00000008", "0?call_ended") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [h@sub-local-dialing:5] GosubIf("PJSIP/101-00000008", "0?wake-up-no-answered,s,1") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [h@sub-local-dialing:6] Gosub("PJSIP/101-00000008", "sub-call-completion-check,s,1") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-call-completion-check:1] NoOp("PJSIP/101-00000008", "FEATURE: CALL COMPLETION CHECK") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-call-completion-check:2] Set("PJSIP/101-00000008", "CC_MODE=none") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-call-completion-check:3] Set("PJSIP/101-00000008", "CC_MODE=none") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-call-completion-check:4] Set("PJSIP/101-00000008", "CC_MODE=none") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-call-completion-check:5] GotoIf("PJSIP/101-00000008", "1?end") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (sub-call-completion-check,s,17)
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@sub-call-completion-check:17] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [h@sub-local-dialing:7] NoOp("PJSIP/101-00000008", "Call ended") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [h@sub-local-dialing:8] Gosub("PJSIP/101-00000008", "notify-call-hangup,s,1") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@notify-call-hangup:1] Verbose("PJSIP/101-00000008", "0, Extension Hangup") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] app_verbose.c: Extension Hangup
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@notify-call-hangup:2] GotoIf("PJSIP/101-00000008", "1?:incoming") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@notify-call-hangup:3] UserEvent("PJSIP/101-00000008", ""EXTENSIONS_SUMMARY","Data: EXT_TO_EXT,PJSIP/101-0000001v,,LOCAL"") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@notify-call-hangup:4] Goto("PJSIP/101-00000008", "end") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx_builtins.c: Goto (notify-call-hangup,s,7)
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [s@notify-call-hangup:7] Return("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Executing [h@sub-local-dialing:9] Hangup("PJSIP/101-00000008", "") in new stack
[2022-10-27 23:12:11] VERBOSE[2738][C-00000005] pbx.c: Spawn extension (sub-local-dialing, h, 9) exited non-zero on 'PJSIP/101-00000008'
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] bridge_channel.c: Channel PJSIP/102-00000006 left 'simple_bridge' basic-bridge <9b0f5c9f-8cdd-4be4-9faa-4c1ff1698a80>
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Spawn extension (trk-1-dial, 2203081287264002, 45) exited non-zero on 'PJSIP/102-00000006'
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [h@trk-1-dial:1] GosubIf("PJSIP/102-00000006", "0?sub-outbound-queue-call,s,1") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [h@trk-1-dial:2] Gosub("PJSIP/102-00000006", "dynamic-routing-out,s,1(102,2203081287264002,ANSWER,1)") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@dynamic-routing-out:1] NoOp("PJSIP/102-00000006", "Test if save on dynamic routing list") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@dynamic-routing-out:2] Set("PJSIP/102-00000006", "CALLER=102") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@dynamic-routing-out:3] Set("PJSIP/102-00000006", "CALLED=2203081287264002") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@dynamic-routing-out:4] Set("PJSIP/102-00000006", "STATUS=ANSWER") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@dynamic-routing-out:5] Set("PJSIP/102-00000006", "TRKID=1") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@dynamic-routing-out:6] Set("PJSIP/102-00000006", "ALLOW_DR=no") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@dynamic-routing-out:7] Set("PJSIP/102-00000006", "DYNROUTING_OMC=yes") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@dynamic-routing-out:8] GotoIf("PJSIP/102-00000006", "0?:comeback") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx_builtins.c: Goto (dynamic-routing-out,s,10)
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@dynamic-routing-out:10] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [h@trk-1-dial:3] Gosub("PJSIP/102-00000006", "notify-trunk-hangup,s,1") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@notify-trunk-hangup:1] Verbose("PJSIP/102-00000006", "0, Trunk Hangup") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] app_verbose.c: Trunk Hangup
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@notify-trunk-hangup:2] Set("PJSIP/102-00000006", "CALLER=PJSIP/102") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@notify-trunk-hangup:3] UserEvent("PJSIP/102-00000006", ""EXTENSIONS_SUMMARY","Data: OUTBOUND,PJSIP/102,081287264002,OUT"") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [s@notify-trunk-hangup:4] Return("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Executing [h@trk-1-dial:4] Hangup("PJSIP/102-00000006", "") in new stack
[2022-10-27 23:12:24] VERBOSE[2646][C-00000004] pbx.c: Spawn extension (trk-1-dial, h, 4) exited non-zero on 'PJSIP/102-00000006'
[2022-10-27 23:12:24] VERBOSE[2655][C-00000004] bridge_channel.c: Channel PJSIP/DEMO-00000007 left 'simple_bridge' basic-bridge <9b0f5c9f-8cdd-4be4-9faa-4c1ff1698a80>
[2022-10-27 23:12:24] VERBOSE[2654][C-00000004] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2022-10-27 23:12:24] VERBOSE[2654][C-00000004] app_mixmonitor.c: End MixMonitor Recording PJSIP/DEMO-00000007
Thanks