Busy Ringtone Not Working

Hi All & Vitalpbx,

Please confirm whether this experiment is compatible with the issue “Ringback tone while called party is on the phone”

I have 2 extensions 101 & 102

Ext 102 makes a call to the customer’s cellphone and talks.

When 102 spoke to the customer, ext 101 made a call to 102, but what happened was the ext 101 agent didn’t hear anything and immediately hangup / fast busy

The following attachments are settings in vitalpbx & logs in asterisk when 101 calls 102

setting
asterisk_log
Thanks

Check your extension settings. The call waiting should be enabled on your extensions and call limit must be grater than one.

Hi @miguel

After update config ext 101 & 102, but 101 still not hear busy tone, 101 only hear tone ringing tone / progress tone.

config_ext

user2_call_to_customer_and_talking

user1_call_to_user2

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

Enable the “Ringing Tone” option in your Dial Profile!

Is this disabled by default or did he disable it some time?

It comes enabled by default. I imagine he disabled the option for some reason!

There is a negative impact if the “Ringing Tone” option is set to “Yes” when making calls to external numbers or to customers.

The experiment I did with the flow call is as follows

  • Agent Call (103) to Customer (6266888888)
  • Ringing on Customer
  • Customer Reject Call
  1. Ringing Tone → Yes, call from ext : 103 to 6266888888 (customer phone number)

Agents can’t listen to notifications from the telco site. agent only listen to fake ringing until call timeout / terminated

  1. Ringing Tone → No, call from ext : 103 to 6266888888 (customer phone number)

Agents can listen to notifications from the telco side. agent listen nofication “The number you are calling is busy, please try a few minutes”

I attach the sngrep of the above experiment

image

Thanks

I’ve done what you asked, actually the experiment I did was right or not?

ext 101 call out to customer number → agent talking with customer . While they are talking ext 102 call ext 101.

Can you give an explanation under normal conditions what it should be like?

Thanks

Did you know that you can create multiple dial profiles? So, you can assign or leave the default for your extensions, a create a new one for your trunks.

On the other hand, the “r” flag ( Ringing Tone) is required to produce the tones according to the tone zone configured on your PJSIP Settings.

OK @miguel

sorry i just understand custom dial profile. :sob:

Thanks