Date Time Sonata Stats VS Date time Sonata Dialer not Match

Hi all & VitalpbxTeam

  1. I found an issue in the sonata stats report in the “call reports → Outgoing Calls” menu where there is a time difference of about 7 hours.

I made a call from ext 201 to the destination number 0431845492 at 12:21 am from the sonata dialer.

In the report sonata dialer (report dialer → call by agents) the data is correct but in the sonata stats it is not correct (difference 7 hours)

Here I attach the picture

  1. the second problem with sonata stats is, when I filter Date “2022-10-27 05:20 - 2022-10-27 23:59” why does the data appear “2022-10-27 05:05:33” ?

I’ve tried changing the timezone to UTC or to GMT+7 → Logout from app → login again → but the “Date” is still the same (Not correct)

  1. Then why in the sonata stat report, 1 call produces 2 records?

Example: a call to the number 0431845492 produces 2 records with the disposition “No Answer” and “Answered”. while in the sonata dialer report there is only 1 record with call status “No Answer”

Reports on sonata stats will be confusing, 1 call produces 2 records.

Thanks

Have you modified the DialPlan?

Could you attach a full call log?

Hi @miguel

No, i dont change dialplan sir!

asterisk logs

[2022-10-27 23:30:00] VERBOSE[4972] pbx_spool.c: Attempting call on Local/201@sub-pbx-dialer/n for 0431845492@sub-pbx-dialer:1 (Retry 1)
[2022-10-27 23:30:00] VERBOSE[4972] dial.c: Called 201@sub-pbx-dialer/n
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-pbx-dialer:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "SUB: Executing PBX Dialer") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-pbx-dialer:2] ExecIf("Local/201@sub-pbx-dialer-00000001;2", "0?Hangup()") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-pbx-dialer:3] Set("Local/201@sub-pbx-dialer-00000001;2", "__FROM_DIALER=yes") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-pbx-dialer:4] Set("Local/201@sub-pbx-dialer-00000001;2", "__SKIP_ANSWER=yes") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-pbx-dialer:5] Set("Local/201@sub-pbx-dialer-00000001;2", "__FROM_QUEUE_CALLBACK=yes") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-pbx-dialer:6] Set("Local/201@sub-pbx-dialer-00000001;2", "__PD_EXECUTED=yes") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-pbx-dialer:7] Goto("Local/201@sub-pbx-dialer-00000001;2", "cos-all,201,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (cos-all,201,1)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@cos-all:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "More than on digit pattern") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@cos-all:2] Gosub("Local/201@sub-pbx-dialer-00000001;2", "s,1(201)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@cos-all:1] Set("Local/201@sub-pbx-dialer-00000001;2", "EXTENSION=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@cos-all:2] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Dialing 201 from 0431845492") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@cos-all:3] Gosub("Local/201@sub-pbx-dialer-00000001;2", "sub-set-global-vars,s,1(421915deea377c0d,201,parking-1)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-global-vars:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Setting Global Vars") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-global-vars:2] Set("Local/201@sub-pbx-dialer-00000001;2", "TENANT_PATH=421915deea377c0d") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-global-vars:3] Set("Local/201@sub-pbx-dialer-00000001;2", "PREFIX=") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-global-vars:4] Set("Local/201@sub-pbx-dialer-00000001;2", "__PICKUPMARK=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-global-vars:5] Set("Local/201@sub-pbx-dialer-00000001;2", "__PARKINGLOT=parking-1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-global-vars:6] Set("Local/201@sub-pbx-dialer-00000001;2", "__DYNAMIC_FEATURES=custom-features") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-global-vars:7] Set("Local/201@sub-pbx-dialer-00000001;2", "ICALL=no") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-global-vars:8] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@cos-all:4] Gosub("Local/201@sub-pbx-dialer-00000001;2", "sub-set-call-vars,s,1(421915deea377c0d,201,1,cos-all,ARS-all)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Setting Call Vars") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:2] Set("Local/201@sub-pbx-dialer-00000001;2", "_TENANT=421915deea377c0d") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:3] Set("Local/201@sub-pbx-dialer-00000001;2", "_CALL_DESTINATION=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:4] Set("Local/201@sub-pbx-dialer-00000001;2", "_COS_ID=1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:5] Set("Local/201@sub-pbx-dialer-00000001;2", "_COS_NAME=cos-all") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:6] Set("Local/201@sub-pbx-dialer-00000001;2", "__TRANSFER_CONTEXT=cos-all") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:7] Set("Local/201@sub-pbx-dialer-00000001;2", "_ARS=ARS-all") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:8] Set("Local/201@sub-pbx-dialer-00000001;2", "CDR(tenant)=vitalpbx") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:9] Set("Local/201@sub-pbx-dialer-00000001;2", "CDR(destination)=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:10] Set("Local/201@sub-pbx-dialer-00000001;2", "CDR(trunk)=") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:11] Set("Local/201@sub-pbx-dialer-00000001;2", "CDR(did)=") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:12] Set("Local/201@sub-pbx-dialer-00000001;2", "__CALLID=1666888200.35") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:13] ExecIf("Local/201@sub-pbx-dialer-00000001;2", "0?Set(CHANNEL(language)=)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:14] ExecIf("Local/201@sub-pbx-dialer-00000001;2", "0?Set(CHANNEL(language)=)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-call-vars:15] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@cos-all:5] Gosub("Local/201@sub-pbx-dialer-00000001;2", "sub-construct-cid,s,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-construct-cid:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Building CID") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-construct-cid:2] Set("Local/201@sub-pbx-dialer-00000001;2", "_CALL_SOURCE=0431845492") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-construct-cid:3] Set("Local/201@sub-pbx-dialer-00000001;2", "_CS=0431845492") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-construct-cid:4] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?return") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-construct-cid:5] Gosub("Local/201@sub-pbx-dialer-00000001;2", "sub-get-device-tree,s,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-get-device-tree:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Configuring Device Tree") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-get-device-tree:2] Set("Local/201@sub-pbx-dialer-00000001;2", "DEV_TECHNOLOGY=Local") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-get-device-tree:3] GosubIf("Local/201@sub-pbx-dialer-00000001;2", "1?s-local,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-local@sub-get-device-tree:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Try to determianate caller technology 0431845492") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-local@sub-get-device-tree:2] Set("Local/201@sub-pbx-dialer-00000001;2", "TECH_DETERMINATED=no") in new stack
[2022-10-27 23:30:00] WARNING[4973][C-0000000b] func_channel.c: Unknown or unavailable item requested: 'dahdi_channel'
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-local@sub-get-device-tree:3] Set("Local/201@sub-pbx-dialer-00000001;2", "DEV_TECHNOLOGY=Local") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-local@sub-get-device-tree:4] Set("Local/201@sub-pbx-dialer-00000001;2", "DEV_TECHNOLOGY=Local") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-local@sub-get-device-tree:5] Set("Local/201@sub-pbx-dialer-00000001;2", "DEV_TECHNOLOGY=Local") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-local@sub-get-device-tree:6] Set("Local/201@sub-pbx-dialer-00000001;2", "TECH_DETERMINATED=no") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-local@sub-get-device-tree:7] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-get-device-tree:4] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "1?return") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-get-device-tree,s,15)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-get-device-tree:15] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-construct-cid:6] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "1?return") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-construct-cid,s,11)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-construct-cid:11] NoOp("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-construct-cid:12] Set("Local/201@sub-pbx-dialer-00000001;2", "CDR(source)=0431845492") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-construct-cid:13] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@cos-all:6] Gosub("Local/201@sub-pbx-dialer-00000001;2", "set-global-tenant-vars,s,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@set-global-tenant-vars:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Setting Global Vars for vitalpbx Tenant") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@set-global-tenant-vars:2] Set("Local/201@sub-pbx-dialer-00000001;2", "__TENANT_PATH=421915deea377c0d") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@set-global-tenant-vars:3] Set("Local/201@sub-pbx-dialer-00000001;2", "__TENANT_PREFIX=") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@set-global-tenant-vars:4] Set("Local/201@sub-pbx-dialer-00000001;2", "__QUEUE_AGENTS_CONTEXT=queue-call-to-agents") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@set-global-tenant-vars:5] Set("Local/201@sub-pbx-dialer-00000001;2", "__FOLLOWME_CONTEXT=ext-followme") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@set-global-tenant-vars:6] Set("Local/201@sub-pbx-dialer-00000001;2", "__HINTS_CONTEXT=extension-hints") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@set-global-tenant-vars:7] Set("Local/201@sub-pbx-dialer-00000001;2", "__DEFAULT_COS=cos-all") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@set-global-tenant-vars:8] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@cos-all:7] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?local-dialing") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@cos-all:8] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Check if is an Emergency Call") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@cos-all:9] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?emergency-calls,201,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@cos-all:10] Gosub("Local/201@sub-pbx-dialer-00000001;2", "sub-lockphone-check,s,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-lockphone-check:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "SUB: LOCK PHONE CHECK") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-lockphone-check:2] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?unlock") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-lockphone-check:3] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?unlock") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-lockphone-check:4] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?unlock") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-lockphone-check:5] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?unlock") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-lockphone-check:6] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?:unlock") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-lockphone-check,s,12)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-lockphone-check:12] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@cos-all:11] Gosub("Local/201@sub-pbx-dialer-00000001;2", "sub-local-dialing,s,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-local-dialing:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Trying to determine if is a local extension") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-local-dialing:2] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "1?:return") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-local-dialing:3] Set("Local/201@sub-pbx-dialer-00000001;2", "EXTENSION_TO_DIAL=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-local-dialing:4] Goto("Local/201@sub-pbx-dialer-00000001;2", "201,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-local-dialing,201,1)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Dialing Local Extension") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:2] ExecIf("Local/201@sub-pbx-dialer-00000001;2", "0?NoCDR()") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:3] Gosub("Local/201@sub-pbx-dialer-00000001;2", "sub-setup-call-type,s,1(internal)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Determinating Call Type") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?return") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("Local/201@sub-pbx-dialer-00000001;2", "s-internal,1()") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-internal@sub-setup-call-type:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Internal Call") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-internal@sub-setup-call-type:2] Set("Local/201@sub-pbx-dialer-00000001;2", "__CALL_TYPE=1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-internal@sub-setup-call-type:3] Set("Local/201@sub-pbx-dialer-00000001;2", "__CALL_TYPE_LABEL=LOCAL") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-internal@sub-setup-call-type:4] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-setup-call-type:4] Set("Local/201@sub-pbx-dialer-00000001;2", "__CALL_TYPE_CONFIGURED=yes") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-setup-call-type:5] Set("Local/201@sub-pbx-dialer-00000001;2", "CDR(calltype)=1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-setup-call-type:6] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:4] Set("Local/201@sub-pbx-dialer-00000001;2", "_DESTINATION_STATE=NOT_INUSE") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:5] Set("Local/201@sub-pbx-dialer-00000001;2", "_DESTINATION_NUMBER=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:6] Set("Local/201@sub-pbx-dialer-00000001;2", "_DESTINATION_COS_NAME=cos-all") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:7] Gosub("Local/201@sub-pbx-dialer-00000001;2", "sub-check-cos-privacy,s,1(cos-all,1,cos-all)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-cos-privacy:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Checking Privacy of CoS: cos-all") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-cos-privacy:2] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "1?:no_local") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-cos-privacy:3] Set("Local/201@sub-pbx-dialer-00000001;2", "CALLER_COS_ID=1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-cos-privacy:4] Set("Local/201@sub-pbx-dialer-00000001;2", "CALLER_COS_NAME=cos-all") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-cos-privacy:5] Set("Local/201@sub-pbx-dialer-00000001;2", "CALLED_COS_ID=1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-cos-privacy:6] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "1?same_cos") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-check-cos-privacy,s,17)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-cos-privacy:17] NoOp("Local/201@sub-pbx-dialer-00000001;2", "The caller CoS and Callee CoS are the same") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-cos-privacy:18] Goto("Local/201@sub-pbx-dialer-00000001;2", "return") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-check-cos-privacy,s,22)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-cos-privacy:22] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:8] Set("Local/201@sub-pbx-dialer-00000001;2", "DIAL_STRING=PJSIP/201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:9] Set("Local/201@sub-pbx-dialer-00000001;2", "DB(421915deea377c0d/LASTCALLER/201)=0431845492") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:10] Set("Local/201@sub-pbx-dialer-00000001;2", "IGNORE_DIVERSIONS=") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:11] Set("Local/201@sub-pbx-dialer-00000001;2", "QUEUE_CALL=") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:12] GosubIf("Local/201@sub-pbx-dialer-00000001;2", "0?sub-queue-members,s,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:13] GosubIf("Local/201@sub-pbx-dialer-00000001;2", "0?:sub-check-diversions,s,1(predialing)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-diversions:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Checking diversions for extension 201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-diversions:2] Set("Local/201@sub-pbx-dialer-00000001;2", "OLD_CALLER_ID="Sonata Dialer| 0431845492" <0431845492>") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-diversions:3] GosubIf("Local/201@sub-pbx-dialer-00000001;2", "0?:return") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-diversions:9] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-diversions:4] ExecIf("Local/201@sub-pbx-dialer-00000001;2", "0?Set(CALLERID(all)="201" <201>)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-diversions:5] Set("Local/201@sub-pbx-dialer-00000001;2", "__CALL_ORIGIN=diversions") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-diversions:6] Set("Local/201@sub-pbx-dialer-00000001;2", "__DIVERSION_ORIGIN=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-diversions:7] Gosub("Local/201@sub-pbx-dialer-00000001;2", "s-predialing,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-predialing@sub-check-diversions:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Diversions on pre-dialing") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-predialing@sub-check-diversions:2] GosubIf("Local/201@sub-pbx-dialer-00000001;2", "0?:diversion-testing,1(Do not Disturb,DND,,,dnd)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:2] Set("Local/201@sub-pbx-dialer-00000001;2", "FROM_NUMBER=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:3] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?:next-diversion") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-check-diversions,diversion-testing,36)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:36] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-predialing@sub-check-diversions:3] Gosub("Local/201@sub-pbx-dialer-00000001;2", "diversion-testing,1(Call Forward Immeditely,CFI,,,toggle_cfi)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:2] Set("Local/201@sub-pbx-dialer-00000001;2", "FROM_NUMBER=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:3] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?:next-diversion") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-check-diversions,diversion-testing,36)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:36] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-predialing@sub-check-diversions:4] GosubIf("Local/201@sub-pbx-dialer-00000001;2", "0?:diversion-testing,1(Boss Secretray,BOSS,,,boss_secretary)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:2] Set("Local/201@sub-pbx-dialer-00000001;2", "FROM_NUMBER=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:3] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?:next-diversion") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-check-diversions,diversion-testing,36)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:36] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-predialing@sub-check-diversions:5] GosubIf("Local/201@sub-pbx-dialer-00000001;2", "0?:diversion-testing,1(Personal Assistant,PEA,sub-personal-assistant,s,1)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:2] Set("Local/201@sub-pbx-dialer-00000001;2", "FROM_NUMBER=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:3] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?:next-diversion") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-check-diversions,diversion-testing,36)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [diversion-testing@sub-check-diversions:36] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s-predialing@sub-check-diversions:6] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-diversions:8] Set("Local/201@sub-pbx-dialer-00000001;2", "CALLERID(all)="Sonata Dialer| 0431845492" <0431845492>") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-check-diversions:9] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:14] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?post-dial") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:15] Gosub("Local/201@sub-pbx-dialer-00000001;2", "parse-dial-string,s,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Parsing dial string: PJSIP/201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:2] Set("Local/201@sub-pbx-dialer-00000001;2", "NEW_DIAL_STRING=") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:3] Set("Local/201@sub-pbx-dialer-00000001;2", "COUNTER=1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:4] Set("Local/201@sub-pbx-dialer-00000001;2", "CURRENT_DEVICE=PJSIP/201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:5] While("Local/201@sub-pbx-dialer-00000001;2", "1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:6] Set("Local/201@sub-pbx-dialer-00000001;2", "TECHNOLOGY=PJSIP") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:7] Set("Local/201@sub-pbx-dialer-00000001;2", "USER=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:8] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "1?:regular") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:9] Goto("Local/201@sub-pbx-dialer-00000001;2", "regular-pjsip") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (parse-dial-string,s,12)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:12] Set("Local/201@sub-pbx-dialer-00000001;2", "PARSED_DEV=PJSIP/201/sip:139.192.45.67:49161;transport=ws") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:13] ExecIf("Local/201@sub-pbx-dialer-00000001;2", "0?Set(PARSED_DEV=PJSIP/201)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:14] Goto("Local/201@sub-pbx-dialer-00000001;2", "append") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (parse-dial-string,s,16)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:16] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "1?:concatenate") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:17] Set("Local/201@sub-pbx-dialer-00000001;2", "NEW_DIAL_STRING=PJSIP/201/sip:139.192.45.67:49161;transport=ws") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:18] Goto("Local/201@sub-pbx-dialer-00000001;2", "next") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (parse-dial-string,s,21)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:21] Set("Local/201@sub-pbx-dialer-00000001;2", "COUNTER=2") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:22] Set("Local/201@sub-pbx-dialer-00000001;2", "CURRENT_DEVICE=") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:23] EndWhile("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:5] While("Local/201@sub-pbx-dialer-00000001;2", "0") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:24] NoOp("Local/201@sub-pbx-dialer-00000001;2", "New dialstring: PJSIP/201/sip:139.192.45.67:49161;transport=ws") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:25] Set("Local/201@sub-pbx-dialer-00000001;2", "DIAL_STRING=PJSIP/201/sip:139.192.45.67:49161;transport=ws") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@parse-dial-string:26] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:16] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Configuring Dial Settings") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:17] Set("Local/201@sub-pbx-dialer-00000001;2", "RING_TIME=30") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:18] Set("Local/201@sub-pbx-dialer-00000001;2", "RING_TIME=30") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:19] Set("Local/201@sub-pbx-dialer-00000001;2", "RING_TIME=30") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:20] Set("Local/201@sub-pbx-dialer-00000001;2", "DIAL_OPTIONS=kKt") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:21] Set("Local/201@sub-pbx-dialer-00000001;2", "DIAL_OPTIONS=kKt") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:22] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?:answermode") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-local-dialing,201,26)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:26] Set("Local/201@sub-pbx-dialer-00000001;2", "ANSWER_MODE=disable") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:27] Set("Local/201@sub-pbx-dialer-00000001;2", "ANSWER_MODE=disable") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:28] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?check-hd") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:29] ExecIf("Local/201@sub-pbx-dialer-00000001;2", "1?Set(EXEC_AA=yes)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:30] ExecIf("Local/201@sub-pbx-dialer-00000001;2", "1?Set(EXEC_AA=yes)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:31] Set("Local/201@sub-pbx-dialer-00000001;2", "IS_HOT_DESK=no") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:32] Set("Local/201@sub-pbx-dialer-00000001;2", "MOH_CLASS=default") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:33] GosubIf("Local/201@sub-pbx-dialer-00000001;2", "0?sub-set-moh,s,1(default)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:34] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?busy-here") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:35] Set("Local/201@sub-pbx-dialer-00000001;2", "FORCE_RECORDING=no") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:36] Set("Local/201@sub-pbx-dialer-00000001;2", "DIAL_OPTIONS=kKtb(sub-before-connecting-call^s^1(421915deea377c0d^0431845492^201^yes^))") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:37] Set("Local/201@sub-pbx-dialer-00000001;2", "DIAL_OPTIONS=kKtb(sub-before-connecting-call^s^1(421915deea377c0d^0431845492^201^yes^))U(sub-before-bridging-call^421915deea377c0d^0431845492^201^no)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:38] Set("Local/201@sub-pbx-dialer-00000001;2", "DIAL_OPTIONS=kKtb(sub-before-connecting-call^s^1(421915deea377c0d^0431845492^201^yes^))U(sub-before-bridging-call^421915deea377c0d^0431845492^201^no)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:39] Set("Local/201@sub-pbx-dialer-00000001;2", "__TRANSFERED_CALL=TRUE") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:40] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Enable Follow-Me Ring Time If needed") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:41] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?:check-rt") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-local-dialing,201,48)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:48] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?post-dial") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:49] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?end_call") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:50] Gosub("Local/201@sub-pbx-dialer-00000001;2", "sub-udpate-virtual-dev-state,s,1(201,BUSY)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Update Virtual Devices State") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:2] Set("Local/201@sub-pbx-dialer-00000001;2", "EXT_NUM=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:3] Set("Local/201@sub-pbx-dialer-00000001;2", "NEW_STATUS=BUSY") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:4] Set("Local/201@sub-pbx-dialer-00000001;2", "EXTENSION_ID=3") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:5] Set("Local/201@sub-pbx-dialer-00000001;2", "ODBC_ID=") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:6] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "1?no_rows") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-udpate-virtual-dev-state,s,14)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:14] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [201@sub-local-dialing:51] Dial("Local/201@sub-pbx-dialer-00000001;2", "PJSIP/201/sip:139.192.45.67:49161;transport=ws,30,kKtb(sub-before-connecting-call^s^1(421915deea377c0d^0431845492^201^yes^))U(sub-before-bridging-call^421915deea377c0d^0431845492^201^no)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] app_stack.c: PJSIP/201-00000012 Internal Gosub(sub-before-connecting-call,s,1(421915deea377c0d,0431845492,201,yes,)) start
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:1] NoOp("PJSIP/201-00000012", "Executing routines in the newly created channel before initiaing the call") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:2] Set("PJSIP/201-00000012", "TENANT=421915deea377c0d") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:3] Set("PJSIP/201-00000012", "CALLER=0431845492") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:4] Set("PJSIP/201-00000012", "CALLEE=201") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:5] Set("PJSIP/201-00000012", "EXEC_AA=yes") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:6] Set("PJSIP/201-00000012", "CUSTOM_ALERT_INFO=") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:7] Set("PJSIP/201-00000012", "APP_TYPE=NONE") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:8] GosubIf("PJSIP/201-00000012", "1?sub-auto-answer,s,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:1] NoOp("PJSIP/201-00000012", "Set up PJSIP Headers") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:2] GotoIf("PJSIP/201-00000012", "0?set-sip") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:3] NoOp("PJSIP/201-00000012", "Add PJSIP Headers") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:4] Set("PJSIP/201-00000012", "PJSIP_HEADER(add,Call-Info)=<uri>;answer-after=0") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:5] Set("PJSIP/201-00000012", "PJSIP_HEADER(add,Alert-Info)=Answer") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:6] Set("PJSIP/201-00000012", "PJSIP_HEADER(add,Alert-Info)=Ring Answer") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:7] Set("PJSIP/201-00000012", "PJSIP_HEADER(add,intercom)=true") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:8] Set("PJSIP/201-00000012", "PJSIP_HEADER(add,Call-Info)=<sip:broadworks.net>;answer-after=0") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:9] Set("PJSIP/201-00000012", "PJSIP_HEADER(add,Alert-Info)=Intercom") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:10] Set("PJSIP/201-00000012", "PJSIP_HEADER(add,Alert-Info)=info=Auto Answer") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:11] Set("PJSIP/201-00000012", "PJSIP_HEADER(add,Alert-Info)=ring-answer") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:12] NoOp("PJSIP/201-00000012", "Add SIP Headers") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:13] SIPAddHeader("PJSIP/201-00000012", "Call-Info: <uri>;answer-after=0") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:14] SIPAddHeader("PJSIP/201-00000012", "Alert-Info: Answer") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:15] SIPAddHeader("PJSIP/201-00000012", "Alert-Info: Ring Answer") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:16] SIPAddHeader("PJSIP/201-00000012", "intercom=true") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:17] SIPAddHeader("PJSIP/201-00000012", "Call-Info: <sip:broadworks.net>;answer-after=0") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:18] SIPAddHeader("PJSIP/201-00000012", "Alert-Info: Intercom") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:19] SIPAddHeader("PJSIP/201-00000012", "Alert-Info: info=Auto Answer") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:20] SIPAddHeader("PJSIP/201-00000012", "Alert-Info: ring-answer") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-auto-answer:21] Return("PJSIP/201-00000012", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:9] GosubIf("PJSIP/201-00000012", "0?sub-set-custom-header,s,1(Alert-Info,)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:10] GosubIf("PJSIP/201-00000012", "0?sub-set-custom-header,s,1(Call-Info,)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:11] Gosub("PJSIP/201-00000012", "sub-set-custom-header,s,1(CALL-TYPE,LOCAL)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:1] NoOp("PJSIP/201-00000012", "Setting up custom header") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:2] GotoIf("PJSIP/201-00000012", "0?set-sip") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:3] NoOp("PJSIP/201-00000012", "Add PJSIP Headers") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:4] Set("PJSIP/201-00000012", "PJSIP_HEADER(add,CALL-TYPE)=LOCAL") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:5] Goto("PJSIP/201-00000012", "end") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-set-custom-header,s,8)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:8] Return("PJSIP/201-00000012", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:12] Gosub("PJSIP/201-00000012", "sub-set-custom-header,s,1(APPLICATION,NONE)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:1] NoOp("PJSIP/201-00000012", "Setting up custom header") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:2] GotoIf("PJSIP/201-00000012", "0?set-sip") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:3] NoOp("PJSIP/201-00000012", "Add PJSIP Headers") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:4] Set("PJSIP/201-00000012", "PJSIP_HEADER(add,APPLICATION)=NONE") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:5] Goto("PJSIP/201-00000012", "end") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-set-custom-header,s,8)
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-set-custom-header:8] Return("PJSIP/201-00000012", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:13] GosubIf("PJSIP/201-00000012", "0?sub-set-custom-header,s,1(QUEUE-NAME,)") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:14] GosubIf("PJSIP/201-00000012", "0?before-connecting-call-hook,s,1") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-connecting-call:15] Return("PJSIP/201-00000012", "") in new stack
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] app_stack.c: Spawn extension (cos-all, 201, 1) exited non-zero on 'PJSIP/201-00000012'
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] app_stack.c: PJSIP/201-00000012 Internal Gosub(sub-before-connecting-call,s,1(421915deea377c0d,0431845492,201,yes,)) complete GOSUB_RETVAL=
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] app_dial.c: Called PJSIP/201/sip:139.192.45.67:49161;transport=ws
[2022-10-27 23:30:00] VERBOSE[1606] res_rtp_asterisk.c: DTLS ECDH initialized (automatic), faster PFS enabled
[2022-10-27 23:30:00] VERBOSE[4973][C-0000000b] app_dial.c: PJSIP/201-00000012 is ringing
[2022-10-27 23:30:00] VERBOSE[4972] dial.c: Local/201@sub-pbx-dialer-00000001;1 is ringing
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] app_dial.c: PJSIP/201-00000012 answered Local/201@sub-pbx-dialer-00000001;2
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] app_stack.c: PJSIP/201-00000012 Internal Gosub(sub-before-bridging-call,s,1(421915deea377c0d,0431845492,201,no)) start
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:1] NoOp("PJSIP/201-00000012", "Executing sub-routine before connecting to the calling channel") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:2] Set("PJSIP/201-00000012", "TENANT=421915deea377c0d") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:3] Set("PJSIP/201-00000012", "CALLER=0431845492") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:4] Set("PJSIP/201-00000012", "CALLEE=201") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:5] Set("PJSIP/201-00000012", "FORCE_CALL_RECORDING=no") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:6] NoOp("PJSIP/201-00000012", "Check if the follow-me confirmation sub-routine needs to be executed") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:7] GotoIf("PJSIP/201-00000012", "1?call-recording") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-before-bridging-call,s,9)
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:9] NoOp("PJSIP/201-00000012", "Executing the call-recording sub-routine") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:10] Gosub("PJSIP/201-00000012", "sub-call-recording,s,1(421915deea377c0d,0431845492,201,no)") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:1] NoOp("PJSIP/201-00000012", "Check if call will be recorded on tenant 421915deea377c0d") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:2] NoOp("PJSIP/201-00000012", "Call Type: 1 ") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:3] Set("PJSIP/201-00000012", "TENANT=421915deea377c0d") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:4] Set("PJSIP/201-00000012", "CALLER=0431845492") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:5] Set("PJSIP/201-00000012", "CALLEE=201") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:6] Set("PJSIP/201-00000012", "APP_FORCE=no") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:7] GotoIf("PJSIP/201-00000012", "1?:rec_no_available") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:8] GotoIf("PJSIP/201-00000012", "0?perform-recording") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:9] GotoIf("PJSIP/201-00000012", "0?perform-recording") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:10] GotoIf("PJSIP/201-00000012", "0?perform-recording") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:11] GotoIf("PJSIP/201-00000012", "0?perform-recording") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:12] GotoIf("PJSIP/201-00000012", "0?perform-recording") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:13] Goto("PJSIP/201-00000012", "return") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-call-recording,s,17)
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-recording:17] Return("PJSIP/201-00000012", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:11] NoOp("PJSIP/201-00000012", "Check if there's a custom sub-routine defined for executing it") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:12] GosubIf("PJSIP/201-00000012", "0?before-bridging-call-hook,s,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-before-bridging-call:13] Return("PJSIP/201-00000012", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] app_stack.c: Spawn extension (cos-all, , 1) exited non-zero on 'PJSIP/201-00000012'
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] app_stack.c: PJSIP/201-00000012 Internal Gosub(sub-before-bridging-call,s,1(421915deea377c0d,0431845492,201,no)) complete GOSUB_RETVAL=
[2022-10-27 23:30:01] VERBOSE[4972] dial.c: Local/201@sub-pbx-dialer-00000001;1 answered
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@sub-pbx-dialer:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "SUB: Executing PBX Dialer") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@sub-pbx-dialer:2] ExecIf("Local/201@sub-pbx-dialer-00000001;1", "0?Hangup()") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@sub-pbx-dialer:3] Set("Local/201@sub-pbx-dialer-00000001;1", "__FROM_DIALER=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@sub-pbx-dialer:4] Set("Local/201@sub-pbx-dialer-00000001;1", "__SKIP_ANSWER=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@sub-pbx-dialer:5] Set("Local/201@sub-pbx-dialer-00000001;1", "__FROM_QUEUE_CALLBACK=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@sub-pbx-dialer:6] Set("Local/201@sub-pbx-dialer-00000001;1", "__PD_EXECUTED=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@sub-pbx-dialer:7] Goto("Local/201@sub-pbx-dialer-00000001;1", "cos-all,0431845492,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (cos-all,0431845492,1)
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@cos-all:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "More than on digit pattern") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@cos-all:2] Gosub("Local/201@sub-pbx-dialer-00000001;1", "s,1(0431845492)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:1] Set("Local/201@sub-pbx-dialer-00000001;1", "EXTENSION=0431845492") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:2] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Dialing 0431845492 from 201") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:3] Gosub("Local/201@sub-pbx-dialer-00000001;1", "sub-set-global-vars,s,1(421915deea377c0d,0431845492,parking-1)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-global-vars:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Setting Global Vars") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-global-vars:2] Set("Local/201@sub-pbx-dialer-00000001;1", "TENANT_PATH=421915deea377c0d") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-global-vars:3] Set("Local/201@sub-pbx-dialer-00000001;1", "PREFIX=") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-global-vars:4] Set("Local/201@sub-pbx-dialer-00000001;1", "__PICKUPMARK=0431845492") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-global-vars:5] Set("Local/201@sub-pbx-dialer-00000001;1", "__PARKINGLOT=parking-1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-global-vars:6] Set("Local/201@sub-pbx-dialer-00000001;1", "__DYNAMIC_FEATURES=custom-features") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-global-vars:7] Set("Local/201@sub-pbx-dialer-00000001;1", "ICALL=no") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-global-vars:8] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:4] Gosub("Local/201@sub-pbx-dialer-00000001;1", "sub-set-call-vars,s,1(421915deea377c0d,0431845492,1,cos-all,ARS-all)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Setting Call Vars") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:2] Set("Local/201@sub-pbx-dialer-00000001;1", "_TENANT=421915deea377c0d") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:3] Set("Local/201@sub-pbx-dialer-00000001;1", "_CALL_DESTINATION=0431845492") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:4] Set("Local/201@sub-pbx-dialer-00000001;1", "_COS_ID=1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:5] Set("Local/201@sub-pbx-dialer-00000001;1", "_COS_NAME=cos-all") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:6] Set("Local/201@sub-pbx-dialer-00000001;1", "__TRANSFER_CONTEXT=cos-all") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:7] Set("Local/201@sub-pbx-dialer-00000001;1", "_ARS=ARS-all") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:8] Set("Local/201@sub-pbx-dialer-00000001;1", "CDR(tenant)=vitalpbx") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:9] Set("Local/201@sub-pbx-dialer-00000001;1", "CDR(destination)=0431845492") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:10] Set("Local/201@sub-pbx-dialer-00000001;1", "CDR(trunk)=") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:11] Set("Local/201@sub-pbx-dialer-00000001;1", "CDR(did)=") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:12] Set("Local/201@sub-pbx-dialer-00000001;1", "__CALLID=1666888200.34") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:13] ExecIf("Local/201@sub-pbx-dialer-00000001;1", "0?Set(CHANNEL(language)=)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:14] ExecIf("Local/201@sub-pbx-dialer-00000001;1", "0?Set(CHANNEL(language)=)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-set-call-vars:15] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:5] Gosub("Local/201@sub-pbx-dialer-00000001;1", "sub-construct-cid,s,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-construct-cid:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Building CID") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-construct-cid:2] Set("Local/201@sub-pbx-dialer-00000001;1", "_CALL_SOURCE=201") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-construct-cid:3] Set("Local/201@sub-pbx-dialer-00000001;1", "_CS=201") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-construct-cid:4] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?return") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-construct-cid:5] Gosub("Local/201@sub-pbx-dialer-00000001;1", "sub-get-device-tree,s,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-get-device-tree:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Configuring Device Tree") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-get-device-tree:2] Set("Local/201@sub-pbx-dialer-00000001;1", "DEV_TECHNOLOGY=Local") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-get-device-tree:3] GosubIf("Local/201@sub-pbx-dialer-00000001;1", "1?s-local,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-local@sub-get-device-tree:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Try to determianate caller technology 201") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-local@sub-get-device-tree:2] Set("Local/201@sub-pbx-dialer-00000001;1", "TECH_DETERMINATED=no") in new stack
[2022-10-27 23:30:01] WARNING[4972][C-0000000c] func_channel.c: Unknown or unavailable item requested: 'dahdi_channel'
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-local@sub-get-device-tree:3] Set("Local/201@sub-pbx-dialer-00000001;1", "DEV_TECHNOLOGY=Local") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-local@sub-get-device-tree:4] Set("Local/201@sub-pbx-dialer-00000001;1", "DEV_TECHNOLOGY=Local") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-local@sub-get-device-tree:5] Set("Local/201@sub-pbx-dialer-00000001;1", "DEV_TECHNOLOGY=Local") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-local@sub-get-device-tree:6] Set("Local/201@sub-pbx-dialer-00000001;1", "TECH_DETERMINATED=no") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-local@sub-get-device-tree:7] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-get-device-tree:4] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "1?return") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (sub-get-device-tree,s,15)
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-get-device-tree:15] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-construct-cid:6] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "1?return") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (sub-construct-cid,s,11)
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-construct-cid:11] NoOp("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-construct-cid:12] Set("Local/201@sub-pbx-dialer-00000001;1", "CDR(source)=201") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-construct-cid:13] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:6] Gosub("Local/201@sub-pbx-dialer-00000001;1", "set-global-tenant-vars,s,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@set-global-tenant-vars:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Setting Global Vars for vitalpbx Tenant") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@set-global-tenant-vars:2] Set("Local/201@sub-pbx-dialer-00000001;1", "__TENANT_PATH=421915deea377c0d") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@set-global-tenant-vars:3] Set("Local/201@sub-pbx-dialer-00000001;1", "__TENANT_PREFIX=") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@set-global-tenant-vars:4] Set("Local/201@sub-pbx-dialer-00000001;1", "__QUEUE_AGENTS_CONTEXT=queue-call-to-agents") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@set-global-tenant-vars:5] Set("Local/201@sub-pbx-dialer-00000001;1", "__FOLLOWME_CONTEXT=ext-followme") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@set-global-tenant-vars:6] Set("Local/201@sub-pbx-dialer-00000001;1", "__HINTS_CONTEXT=extension-hints") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@set-global-tenant-vars:7] Set("Local/201@sub-pbx-dialer-00000001;1", "__DEFAULT_COS=cos-all") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@set-global-tenant-vars:8] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:7] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?local-dialing") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:8] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Check if is an Emergency Call") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:9] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?emergency-calls,0431845492,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:10] Gosub("Local/201@sub-pbx-dialer-00000001;1", "sub-lockphone-check,s,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-lockphone-check:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "SUB: LOCK PHONE CHECK") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-lockphone-check:2] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?unlock") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-lockphone-check:3] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?unlock") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-lockphone-check:4] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?unlock") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-lockphone-check:5] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?unlock") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-lockphone-check:6] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?:unlock") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (sub-lockphone-check,s,12)
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-lockphone-check:12] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:11] Gosub("Local/201@sub-pbx-dialer-00000001;1", "sub-local-dialing,s,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-local-dialing:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Trying to determine if is a local extension") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-local-dialing:2] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?:return") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (sub-local-dialing,s,5)
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-local-dialing:5] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:12] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?end-call") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@cos-all:13] Goto("Local/201@sub-pbx-dialer-00000001;1", "cos-all-post,0431845492,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (cos-all-post,0431845492,1)
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@cos-all-post:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Outbound Route: OCDEMO") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@cos-all-post:2] Gosub("Local/201@sub-pbx-dialer-00000001;1", "sub-setup-call-type,s,1(outgoing)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Determinating Call Type") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?return") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("Local/201@sub-pbx-dialer-00000001;1", "s-outgoing,1()") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Outgoing Call") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("Local/201@sub-pbx-dialer-00000001;1", "__CALL_TYPE=3") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("Local/201@sub-pbx-dialer-00000001;1", "__CALL_TYPE_LABEL=OUT") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("Local/201@sub-pbx-dialer-00000001;1", "0?s-transit,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:4] Set("Local/201@sub-pbx-dialer-00000001;1", "__CALL_TYPE_CONFIGURED=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:5] Set("Local/201@sub-pbx-dialer-00000001;1", "CDR(calltype)=3") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:6] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@cos-all-post:3] Set("Local/201@sub-pbx-dialer-00000001;1", "DNID=22030431845492") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@cos-all-post:4] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Start to Dial Trunks") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [0431845492@cos-all-post:5] Goto("Local/201@sub-pbx-dialer-00000001;1", "s-1,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (cos-all-post,s-1,1)
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-1@cos-all-post:1] Set("Local/201@sub-pbx-dialer-00000001;1", "__CONFIGURED_BY_TENANT=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-1@cos-all-post:2] Gosub("Local/201@sub-pbx-dialer-00000001;1", "sub-construct-cid,s-external,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Setting External CID") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:2] Set("Local/201@sub-pbx-dialer-00000001;1", "EXTENSION_NUMBER=201") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:3] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?return") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:4] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?return") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:5] Set("Local/201@sub-pbx-dialer-00000001;1", "EMERGENCY_CALL=no") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:6] Set("Local/201@sub-pbx-dialer-00000001;1", "EXTENSION_EMERGENCY_CID=") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:7] Set("Local/201@sub-pbx-dialer-00000001;1", "DYNAMIC_EXTERNAL_CID=no") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:8] ExecIf("Local/201@sub-pbx-dialer-00000001;1", "1?Set(EXTENSION_EMERGENCY_CID=)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:9] ExecIf("Local/201@sub-pbx-dialer-00000001;1", "1?Set(DYNAMIC_EXTERNAL_CID=no)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:10] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?check-emergency") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:11] Set("Local/201@sub-pbx-dialer-00000001;1", "EXTENSION_EXTERNAL_CID=") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:12] ExecIf("Local/201@sub-pbx-dialer-00000001;1", "1?Set(CALLERID(all)=)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:13] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "1?:check-emergency") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:14] Set("Local/201@sub-pbx-dialer-00000001;1", "TENANT_DEFAULT_EXTERNAL_CID=") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:15] ExecIf("Local/201@sub-pbx-dialer-00000001;1", "0?Set(CALLERID(all)=)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:16] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?:info") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (sub-construct-cid,s-external,30)
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:30] NoOp("Local/201@sub-pbx-dialer-00000001;1", "CID Name: , CID Number: ") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:31] Set("Local/201@sub-pbx-dialer-00000001;1", "SEND_CID=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:32] ExecIf("Local/201@sub-pbx-dialer-00000001;1", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:33] ExecIf("Local/201@sub-pbx-dialer-00000001;1", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:34] Set("Local/201@sub-pbx-dialer-00000001;1", "__EXT_CID_CONSTRUCTED=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:35] Set("Local/201@sub-pbx-dialer-00000001;1", "CDR(source)=201") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-external@sub-construct-cid:36] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-1@cos-all-post:3] Gosub("Local/201@sub-pbx-dialer-00000001;1", "trk-1,22030431845492,1(from-trk-grp)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1:1] Set("Local/201@sub-pbx-dialer-00000001;1", "_NTD=22030431845492") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1:2] Gosub("Local/201@sub-pbx-dialer-00000001;1", "trk-1-dial,22030431845492,1(from-trk-grp)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Trunk: DEMO") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:2] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Dialing: 22030431845492") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:3] Set("Local/201@sub-pbx-dialer-00000001;1", "CDR(trunk)=1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:4] Set("Local/201@sub-pbx-dialer-00000001;1", "SKIP_HANGUP_AUDIOS=no") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:5] Gosub("Local/201@sub-pbx-dialer-00000001;1", "sub-setup-call-type,s,1(outgoing,no)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Determinating Call Type") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?return") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("Local/201@sub-pbx-dialer-00000001;1", "s-outgoing,1(no)") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Outgoing Call") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("Local/201@sub-pbx-dialer-00000001;1", "__CALL_TYPE=3") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("Local/201@sub-pbx-dialer-00000001;1", "__CALL_TYPE_LABEL=OUT") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("Local/201@sub-pbx-dialer-00000001;1", "0?s-transit,1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:4] Set("Local/201@sub-pbx-dialer-00000001;1", "__CALL_TYPE_CONFIGURED=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:5] Set("Local/201@sub-pbx-dialer-00000001;1", "CDR(calltype)=3") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-setup-call-type:6] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:6] ExecIf("Local/201@sub-pbx-dialer-00000001;1", "0?Set(CALLERID(all)=):") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:7] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "1?:after-val-sim-calls") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:8] Set("Local/201@sub-pbx-dialer-00000001;1", "MAX_TENANT_CALLS=0") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:9] Set("Local/201@sub-pbx-dialer-00000001;1", "TENANT_NAME=vitalpbx") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:10] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?:after-val-sim-calls") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (trk-1-dial,22030431845492,18)
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:18] NoOp("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:19] Set("Local/201@sub-pbx-dialer-00000001;1", "MAX_CALLS=2") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:20] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Allowed Outgoing Simultaneous Calls: 2") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:21] Set("Local/201@sub-pbx-dialer-00000001;1", "GROUP()=trk-1") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:22] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?:trk-conf") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (trk-1-dial,22030431845492,26)
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:26] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Configuring Trunk Parameters") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:27] ExecIf("Local/201@sub-pbx-dialer-00000001;1", "1?Set(CHANNEL(musicclass)=default):") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:28] Set("Local/201@sub-pbx-dialer-00000001;1", "__TRUNK_MOH_SET=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:29] Set("Local/201@sub-pbx-dialer-00000001;1", "DIAL_PREFIX=") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:30] Set("Local/201@sub-pbx-dialer-00000001;1", "__TRKDIALOPTIONS=kKt") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:31] Set("Local/201@sub-pbx-dialer-00000001;1", "ORIGINATOR=201") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:32] Set("Local/201@sub-pbx-dialer-00000001;1", "RING_TIME=90") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:33] Set("Local/201@sub-pbx-dialer-00000001;1", "CALLED=22030431845492") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:34] Set("Local/201@sub-pbx-dialer-00000001;1", "TRUNK_ENDPOINT=DEMO") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:35] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Force Recording by Trunk") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:36] Set("Local/201@sub-pbx-dialer-00000001;1", "APP_RECORDING=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:37] Set("Local/201@sub-pbx-dialer-00000001;1", "__CIDNUM=") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:38] Set("Local/201@sub-pbx-dialer-00000001;1", "__CIDNAME=") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:39] Set("Local/201@sub-pbx-dialer-00000001;1", "FORCE_RECORDING=yes") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:40] Set("Local/201@sub-pbx-dialer-00000001;1", "FILTERED_NUMBER=22030431845492") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:41] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?:post-fax") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (trk-1-dial,22030431845492,44)
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:44] NoOp("Local/201@sub-pbx-dialer-00000001;1", "End Fax Section") in new stack
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:45] Dial("Local/201@sub-pbx-dialer-00000001;1", "PJSIP/22030431845492@DEMO,90,kKtU(sub-before-bridging-call^421915deea377c0d^201^22030431845492^yes)") in new stack
[2022-10-27 23:30:01] VERBOSE[4984][C-0000000b] bridge_channel.c: Channel PJSIP/201-00000012 joined 'simple_bridge' basic-bridge <90c589c3-1d93-4ba8-85b6-b009e3180559>
[2022-10-27 23:30:01] VERBOSE[4973][C-0000000b] bridge_channel.c: Channel Local/201@sub-pbx-dialer-00000001;2 joined 'simple_bridge' basic-bridge <90c589c3-1d93-4ba8-85b6-b009e3180559>
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] app_dial.c: Called PJSIP/22030431845492@DEMO
[2022-10-27 23:30:01] VERBOSE[4972][C-0000000c] app_dial.c: Local/201@sub-pbx-dialer-00000001;1 requested media update control 26, passing it to PJSIP/DEMO-00000013
[2022-10-27 23:30:02] VERBOSE[4972][C-0000000c] app_dial.c: Local/201@sub-pbx-dialer-00000001;1 requested media update control 26, passing it to PJSIP/DEMO-00000013
[2022-10-27 23:30:03] VERBOSE[4972][C-0000000c] app_dial.c: PJSIP/DEMO-00000013 is making progress passing it to Local/201@sub-pbx-dialer-00000001;1
[2022-10-27 23:30:03] VERBOSE[4972][C-0000000c] app_dial.c: PJSIP/DEMO-00000013 is making progress passing it to Local/201@sub-pbx-dialer-00000001;1
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] app_dial.c: Everyone is busy/congested at this time (1:1/0/0)
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:46] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?:after-fax") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (trk-1-dial,22030431845492,49)
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:49] NoOp("Local/201@sub-pbx-dialer-00000001;1", "End Fax Section") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:50] NoOp("Local/201@sub-pbx-dialer-00000001;1", "After Dial") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:51] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "1?come-back") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (trk-1-dial,22030431845492,53)
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1-dial:53] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1:3] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "1?come-back") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (trk-1,22030431845492,5)
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [22030431845492@trk-1:5] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-1@cos-all-post:4] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Hangup Cause: 17, Dial Status: BUSY") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-1@cos-all-post:5] ForkCDR("Local/201@sub-pbx-dialer-00000001;1", "e") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-1@cos-all-post:6] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?end_call") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-1@cos-all-post:7] GosubIf("Local/201@sub-pbx-dialer-00000001;1", "1?sub-outbound-queue-call,s,1") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-outbound-queue-call:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Outbound Call From Queue") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-outbound-queue-call:2] QueueUpdate("Local/201@sub-pbx-dialer-00000001;1", "Q800,1666888200.34,201,BUSY,,1|0431845492") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-outbound-queue-call:3] Return("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s-1@cos-all-post:8] Gosub("Local/201@sub-pbx-dialer-00000001;1", "sub-hangup-cause,s,1(17)") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-hangup-cause:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Executing Hangup Cause 17") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-hangup-cause:2] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?hangup") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-hangup-cause:3] NoCDR("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-hangup-cause:4] Progress("Local/201@sub-pbx-dialer-00000001;1", "") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-hangup-cause:5] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "0?invalid_route,1") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [s@sub-hangup-cause:6] GotoIf("Local/201@sub-pbx-dialer-00000001;1", "1?busy,1") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx_builtins.c: Goto (sub-hangup-cause,busy,1)
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [busy@sub-hangup-cause:1] NoOp("Local/201@sub-pbx-dialer-00000001;1", "Busy") in new stack
[2022-10-27 23:30:21] VERBOSE[4972][C-0000000c] pbx.c: Executing [busy@sub-hangup-cause:2] Busy("Local/201@sub-pbx-dialer-00000001;1", "5") in new stack
[2022-10-27 23:30:26] VERBOSE[4972][C-0000000c] pbx.c: Spawn extension (sub-hangup-cause, busy, 2) exited non-zero on 'Local/201@sub-pbx-dialer-00000001;1'
[2022-10-27 23:30:26] NOTICE[4972][C-0000000c] pbx_spool.c: Call completed to Local/201@sub-pbx-dialer/n
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] bridge_channel.c: Channel Local/201@sub-pbx-dialer-00000001;2 left 'simple_bridge' basic-bridge <90c589c3-1d93-4ba8-85b6-b009e3180559>
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Spawn extension (sub-local-dialing, 201, 51) exited non-zero on 'Local/201@sub-pbx-dialer-00000001;2'
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [h@sub-local-dialing:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Finishing the call") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [h@sub-local-dialing:2] Gosub("Local/201@sub-pbx-dialer-00000001;2", "sub-udpate-virtual-dev-state,s,1(201,NOT_INUSE)") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Update Virtual Devices State") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:2] Set("Local/201@sub-pbx-dialer-00000001;2", "EXT_NUM=201") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:3] Set("Local/201@sub-pbx-dialer-00000001;2", "NEW_STATUS=NOT_INUSE") in new stack
[2022-10-27 23:30:26] VERBOSE[4984][C-0000000b] bridge_channel.c: Channel PJSIP/201-00000012 left 'simple_bridge' basic-bridge <90c589c3-1d93-4ba8-85b6-b009e3180559>
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:4] Set("Local/201@sub-pbx-dialer-00000001;2", "EXTENSION_ID=3") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:5] Set("Local/201@sub-pbx-dialer-00000001;2", "ODBC_ID=") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:6] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "1?no_rows") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-udpate-virtual-dev-state,s,14)
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-udpate-virtual-dev-state:14] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [h@sub-local-dialing:3] GosubIf("Local/201@sub-pbx-dialer-00000001;2", "0?dynamic-routing-connected,s,1(ANSWER,0431845492)") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [h@sub-local-dialing:4] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "0?call_ended") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [h@sub-local-dialing:5] GosubIf("Local/201@sub-pbx-dialer-00000001;2", "0?wake-up-no-answered,s,1") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [h@sub-local-dialing:6] Gosub("Local/201@sub-pbx-dialer-00000001;2", "sub-call-completion-check,s,1") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-completion-check:1] NoOp("Local/201@sub-pbx-dialer-00000001;2", "FEATURE: CALL COMPLETION CHECK") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-completion-check:2] Set("Local/201@sub-pbx-dialer-00000001;2", "CC_MODE=none") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-completion-check:3] Set("Local/201@sub-pbx-dialer-00000001;2", "CC_MODE=none") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-completion-check:4] Set("Local/201@sub-pbx-dialer-00000001;2", "CC_MODE=none") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-completion-check:5] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "1?end") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (sub-call-completion-check,s,17)
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@sub-call-completion-check:17] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [h@sub-local-dialing:7] NoOp("Local/201@sub-pbx-dialer-00000001;2", "Call ended") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [h@sub-local-dialing:8] Gosub("Local/201@sub-pbx-dialer-00000001;2", "notify-call-hangup,s,1") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@notify-call-hangup:1] Verbose("Local/201@sub-pbx-dialer-00000001;2", "0, Extension Hangup") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] app_verbose.c:  Extension Hangup
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@notify-call-hangup:2] GotoIf("Local/201@sub-pbx-dialer-00000001;2", "1?:incoming") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@notify-call-hangup:3] UserEvent("Local/201@sub-pbx-dialer-00000001;2", ""EXTENSIONS_SUMMARY","Data: EXT_TO_EXT,-0000001v,PJSIP/201-00000012,LOCAL"") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@notify-call-hangup:4] Goto("Local/201@sub-pbx-dialer-00000001;2", "end") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx_builtins.c: Goto (notify-call-hangup,s,7)
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [s@notify-call-hangup:7] Return("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Executing [h@sub-local-dialing:9] Hangup("Local/201@sub-pbx-dialer-00000001;2", "") in new stack
[2022-10-27 23:30:26] VERBOSE[4973][C-0000000b] pbx.c: Spawn extension (sub-local-dialing, h, 9) exited non-zero on 'Local/201@sub-pbx-dialer-00000001;2'

additional info:
Sonata dialer version : 1.0.3-4
sonata stats version : 1.0.7-6
asterisk version : Asterisk 18.12.1-1

Thanks

The Sonata Dialer shows the final status of the call, while the Stats show all the call attempts.

Additionally, why do you need a dialer if you are limiting the outbound calls to 2 simultaneous calls? I mean, dialers are used to send massive calls.

About the issue with the time in the outcalls please contact me to my email at roger@vitalpbx.com I’ll provide you some instructions to apply a patch

Hi Sir,

Please check email, already request patch

Thanks

Hi @miguel

Yes this correct “The Sonata Dialer shows the final status of the call, while the Stats show all the call attempts.”

I will show you in more detail the experiments I did on the manual sonata dialer call mode

“cust2” has 3 phone numbers

Phone 1 → 0400002
Phone 2 → 0500002
Phone 3 → 0600002

The first number I called was phone1 : 0400002, when ringing on cust2 handphone, the customer rejected it so the agent heard the notification “the number you are calling is busy, please try again”, the agent listened to the notification until it finished, so in this case the call was hangup by telcoside.

Next, the agent makes a call to the number: 0500002, the call is answered by the customer, talks for a while then the call is disconnected by the customer.

I show the difference in the report call on the sonata dialer & sonata stats to the number “0400002” vs “0500002”

Thanks

Maybe you should check the sonata stats report again in the “Outgoing Call Detail” report, because every call made by the callfile (sonata dialer, callback queue)

I just tested “CallBack Queue” → Dial Agents First “Yes”

Queue CallBack Report

Outgoing Call Detail

In “Queue CallBack Report” Try 2/3 means that the call was made 2 times

First call at “2022-10-28 09:57:10” with status “NO ANSWER”
Second call at “2022-10-28 10:01:11” with status “ANSWERED”

The conclusion is: All calls whose status is not “ANSWERED” return the same 2 records (indicated by the same call time)

Thanks

In some cases, the Click to Call generates two CDR logs.

ok, so when I offer my customer to use Sonata dialer, the correct call report is report sonata dialer, I don’t need to give access to Sonata stats, because it will make confuse the customer. In Sonata stats, every call that is not answered will generate two logs cdr

Hi Sir,

i still need an explanation, in what cases in sotfware/addon vitalpbx generate two cdr logs?

Thanks