Have around 60 or so DIDs configured to go various places. Added a new DID with VOIP.ms tonight and configured it as an inbound route to time conditions. Checked the trunk is connected and the old DIDs work properly. Upon call transfer to the new DID it immediately disconnects. Tried redirecting it to the extension and call queue itself and still fails. Any suggestions? Here are the logs:
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@cos-all:1] NoOp("PJSIP/1001_7-00000026", "More than on digit pattern") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@cos-all:2] Gosub("PJSIP/1001_7-00000026", "s,1(5023907387)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:1] Set("PJSIP/1001_7-00000026", "EXTENSION=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:2] NoOp("PJSIP/1001_7-00000026", "Dialing 5023907387 from 1001") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:3] Gosub("PJSIP/1001_7-00000026", "sub-set-global-vars,s,1(787f6b541ee875e3,5023907387,parking-1)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-global-vars:1] NoOp("PJSIP/1001_7-00000026", "Setting Global Vars") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-global-vars:2] Set("PJSIP/1001_7-00000026", "TENANT_PATH=787f6b541ee875e3") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-global-vars:3] Set("PJSIP/1001_7-00000026", "PREFIX=") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-global-vars:4] Set("PJSIP/1001_7-00000026", "__PICKUPMARK=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-global-vars:5] Set("PJSIP/1001_7-00000026", "__PARKINGLOT=parking-1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-global-vars:6] Set("PJSIP/1001_7-00000026", "__DYNAMIC_FEATURES=custom-features") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-global-vars:7] Set("PJSIP/1001_7-00000026", "ICALL=no") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-global-vars:8] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:4] Gosub("PJSIP/1001_7-00000026", "sub-set-call-vars,s,1(787f6b541ee875e3,5023907387,1,cos-all,ARS-all)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:1] NoOp("PJSIP/1001_7-00000026", "Setting Call Vars") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:2] Set("PJSIP/1001_7-00000026", "_TENANT=787f6b541ee875e3") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:3] Set("PJSIP/1001_7-00000026", "_CALL_DESTINATION=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:4] Set("PJSIP/1001_7-00000026", "_COS_ID=1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:5] Set("PJSIP/1001_7-00000026", "_COS_NAME=cos-all") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:6] Set("PJSIP/1001_7-00000026", "__TRANSFER_CONTEXT=cos-all") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:7] Set("PJSIP/1001_7-00000026", "_ARS=ARS-all") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:8] Set("PJSIP/1001_7-00000026", "CDR(tenant)=vitalpbx") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:9] Set("PJSIP/1001_7-00000026", "CDR(destination)=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:10] Set("PJSIP/1001_7-00000026", "CDR(trunk)=") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:11] Set("PJSIP/1001_7-00000026", "CDR(did)=") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:12] Set("PJSIP/1001_7-00000026", "__CALLID=1636342710.235") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:13] ExecIf("PJSIP/1001_7-00000026", "0?Set(CHANNEL(language)=)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-set-call-vars:14] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:5] Gosub("PJSIP/1001_7-00000026", "sub-construct-cid,s,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:1] NoOp("PJSIP/1001_7-00000026", "Building CID") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:2] Set("PJSIP/1001_7-00000026", "_CALL_SOURCE=1001") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:3] Set("PJSIP/1001_7-00000026", "_CS=1001") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:4] GotoIf("PJSIP/1001_7-00000026", "0?return") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:5] Gosub("PJSIP/1001_7-00000026", "sub-get-device-tree,s,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-get-device-tree:1] NoOp("PJSIP/1001_7-00000026", "Configuring Device Tree") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-get-device-tree:2] Set("PJSIP/1001_7-00000026", "DEV_TECHNOLOGY=PJSIP") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-get-device-tree:3] GosubIf("PJSIP/1001_7-00000026", "0?s-local,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-get-device-tree:4] GotoIf("PJSIP/1001_7-00000026", "0?return") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-get-device-tree:5] GotoIf("PJSIP/1001_7-00000026", "1?pjsip") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (sub-get-device-tree,s,9)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-get-device-tree:9] NoOp("PJSIP/1001_7-00000026", "A PJSIP") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-get-device-tree:10] Set("PJSIP/1001_7-00000026", "DEV_USER=1001_7") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-get-device-tree:11] Goto("PJSIP/1001_7-00000026", "build_interface") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (sub-get-device-tree,s,13)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-get-device-tree:13] Set("PJSIP/1001_7-00000026", "_INTERFACE=PJSIP/1001_7") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-get-device-tree:14] Set("PJSIP/1001_7-00000026", "DEV_TREE=devices/PJSIP/1001_7") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-get-device-tree:15] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:6] GotoIf("PJSIP/1001_7-00000026", "0?return") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:7] Set("PJSIP/1001_7-00000026", "_CALL_SOURCE=1001") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:8] Set("PJSIP/1001_7-00000026", "_CS=1001") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:9] ExecIf("PJSIP/1001_7-00000026", "1?Set(CALLERID(all)="HWCS" <1001>)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:10] Set("PJSIP/1001_7-00000026", "_CID_CONSTRUCTED=yes") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:11] NoOp("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:12] Set("PJSIP/1001_7-00000026", "CDR(source)=1001") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-construct-cid:13] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:6] Gosub("PJSIP/1001_7-00000026", "set-global-tenant-vars,s,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@set-global-tenant-vars:1] NoOp("PJSIP/1001_7-00000026", "Setting Global Vars for vitalpbx Tenant") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@set-global-tenant-vars:2] Set("PJSIP/1001_7-00000026", "__TENANT_PATH=787f6b541ee875e3") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@set-global-tenant-vars:3] Set("PJSIP/1001_7-00000026", "__TENANT_PREFIX=") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@set-global-tenant-vars:4] Set("PJSIP/1001_7-00000026", "__QUEUE_AGENTS_CONTEXT=queue-call-to-agents") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@set-global-tenant-vars:5] Set("PJSIP/1001_7-00000026", "__FOLLOWME_CONTEXT=ext-followme") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@set-global-tenant-vars:6] Set("PJSIP/1001_7-00000026", "__HINTS_CONTEXT=extension-hints") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@set-global-tenant-vars:7] Set("PJSIP/1001_7-00000026", "__DEFAULT_COS=cos-all") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@set-global-tenant-vars:8] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:7] GotoIf("PJSIP/1001_7-00000026", "0?local-dialing") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:8] NoOp("PJSIP/1001_7-00000026", "Check if is an Emergency Call") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:9] GotoIf("PJSIP/1001_7-00000026", "0?emergency-calls,5023907387,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:10] Gosub("PJSIP/1001_7-00000026", "sub-lockphone-check,s,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-lockphone-check:1] NoOp("PJSIP/1001_7-00000026", "SUB: LOCK PHONE CHECK") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-lockphone-check:2] GotoIf("PJSIP/1001_7-00000026", "0?unlock") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-lockphone-check:3] GotoIf("PJSIP/1001_7-00000026", "0?unlock") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-lockphone-check:4] GotoIf("PJSIP/1001_7-00000026", "0?unlock") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-lockphone-check:5] GotoIf("PJSIP/1001_7-00000026", "0?unlock") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-lockphone-check:6] GotoIf("PJSIP/1001_7-00000026", "0?:unlock") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (sub-lockphone-check,s,12)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-lockphone-check:12] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:11] Gosub("PJSIP/1001_7-00000026", "sub-local-dialing,s,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-local-dialing:1] NoOp("PJSIP/1001_7-00000026", "Trying to determine if is a local extension") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-local-dialing:2] GotoIf("PJSIP/1001_7-00000026", "0?:return") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (sub-local-dialing,s,5)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-local-dialing:5] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:12] GotoIf("PJSIP/1001_7-00000026", "0?end-call") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@cos-all:13] Goto("PJSIP/1001_7-00000026", "cos-all-post,5023907387,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (cos-all-post,5023907387,1)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@cos-all-post:1] NoOp("PJSIP/1001_7-00000026", "Outbound Route: Outbound Calls") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@cos-all-post:2] Gosub("PJSIP/1001_7-00000026", "sub-setup-call-type,s,1(outgoing)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("PJSIP/1001_7-00000026", "Determinating Call Type") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("PJSIP/1001_7-00000026", "0?return") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("PJSIP/1001_7-00000026", "s-outgoing,1()") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("PJSIP/1001_7-00000026", "Outgoing Call") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("PJSIP/1001_7-00000026", "__CALL_TYPE=3") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("PJSIP/1001_7-00000026", "__CALL_TYPE_LABEL=OUT") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("PJSIP/1001_7-00000026", "0?s-transit,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:4] Set("PJSIP/1001_7-00000026", "__CALL_TYPE_CONFIGURED=yes") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:5] Set("PJSIP/1001_7-00000026", "CDR(calltype)=3") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:6] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@cos-all-post:3] NoOp("PJSIP/1001_7-00000026", "Start to Dial Trunks") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@cos-all-post:4] Set("PJSIP/1001_7-00000026", "DNID=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@cos-all-post:5] Goto("PJSIP/1001_7-00000026", "s-1,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (cos-all-post,s-1,1)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-1@cos-all-post:1] Set("PJSIP/1001_7-00000026", "__CONFIGURED_BY_TENANT=yes") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-1@cos-all-post:2] Gosub("PJSIP/1001_7-00000026", "sub-construct-cid,s-external,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:1] NoOp("PJSIP/1001_7-00000026", "Setting External CID") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:2] Set("PJSIP/1001_7-00000026", "EXTENSION_NUMBER=1001") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:3] GotoIf("PJSIP/1001_7-00000026", "0?return") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:4] GotoIf("PJSIP/1001_7-00000026", "0?return") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:5] Set("PJSIP/1001_7-00000026", "EMERGENCY_CALL=no") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:6] Set("PJSIP/1001_7-00000026", "EXTENSION_EMERGENCY_CID=") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:7] Set("PJSIP/1001_7-00000026", "DYNAMIC_EXTERNAL_CID=no") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:8] ExecIf("PJSIP/1001_7-00000026", "1?Set(EXTENSION_EMERGENCY_CID=)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:9] ExecIf("PJSIP/1001_7-00000026", "1?Set(DYNAMIC_EXTERNAL_CID=no)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:10] GotoIf("PJSIP/1001_7-00000026", "0?check-emergency") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:11] Set("PJSIP/1001_7-00000026", "EXTENSION_EXTERNAL_CID="Wildlife Control" <15024105444>") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:12] ExecIf("PJSIP/1001_7-00000026", "1?Set(CALLERID(all)="Wildlife Control" <15024105444>)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:13] GotoIf("PJSIP/1001_7-00000026", "1?:check-emergency") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:14] Set("PJSIP/1001_7-00000026", "TENANT_DEFAULT_EXTERNAL_CID=") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:15] ExecIf("PJSIP/1001_7-00000026", "0?Set(CALLERID(all)=)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:16] GotoIf("PJSIP/1001_7-00000026", "0?:info") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (sub-construct-cid,s-external,24)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:24] NoOp("PJSIP/1001_7-00000026", "CID Name: Wildlife Control, CID Number: 15024105444") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:25] Set("PJSIP/1001_7-00000026", "SEND_CID=yes") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:26] ExecIf("PJSIP/1001_7-00000026", "0?Set(CALLERID(name-pres)=prohib_passed_screen)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:27] ExecIf("PJSIP/1001_7-00000026", "0?Set(CALLERID(num-pres)=prohib_passed_screen)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:28] Set("PJSIP/1001_7-00000026", "__EXT_CID_CONSTRUCTED=yes") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:29] Set("PJSIP/1001_7-00000026", "CDR(source)=1001") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-external@sub-construct-cid:30] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-1@cos-all-post:3] Gosub("PJSIP/1001_7-00000026", "trk-2,5023907387,1(from-trk-grp)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2:1] Set("PJSIP/1001_7-00000026", "_NTD=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2:2] Gosub("PJSIP/1001_7-00000026", "trk-2-dial,5023907387,1(from-trk-grp)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:1] NoOp("PJSIP/1001_7-00000026", "Trunk: PLIVO") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:2] NoOp("PJSIP/1001_7-00000026", "Dialing: 5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:3] Set("PJSIP/1001_7-00000026", "CDR(trunk)=2") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:4] Set("PJSIP/1001_7-00000026", "SKIP_HANGUP_AUDIOS=no") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:5] Gosub("PJSIP/1001_7-00000026", "sub-setup-call-type,s,1(outgoing,no)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("PJSIP/1001_7-00000026", "Determinating Call Type") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("PJSIP/1001_7-00000026", "0?return") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("PJSIP/1001_7-00000026", "s-outgoing,1(no)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("PJSIP/1001_7-00000026", "Outgoing Call") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("PJSIP/1001_7-00000026", "__CALL_TYPE=3") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("PJSIP/1001_7-00000026", "__CALL_TYPE_LABEL=OUT") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("PJSIP/1001_7-00000026", "0?s-transit,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:4] Set("PJSIP/1001_7-00000026", "__CALL_TYPE_CONFIGURED=yes") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:5] Set("PJSIP/1001_7-00000026", "CDR(calltype)=3") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:6] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:6] ExecIf("PJSIP/1001_7-00000026", "0?Set(CALLERID(all)=):") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:7] GotoIf("PJSIP/1001_7-00000026", "0?:after-val-sim-calls") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (trk-2-dial,5023907387,18)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:18] NoOp("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:19] NoOp("PJSIP/1001_7-00000026", "Configuring Trunk Parameters") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:20] ExecIf("PJSIP/1001_7-00000026", "1?Set(CHANNEL(musicclass)=default):") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:21] Set("PJSIP/1001_7-00000026", "__TRUNK_MOH_SET=yes") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:22] Set("PJSIP/1001_7-00000026", "__TRKDIALOPTIONS=kKtr") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:23] Set("PJSIP/1001_7-00000026", "ORIGINATOR=1001") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:24] Set("PJSIP/1001_7-00000026", "RING_TIME=90") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:25] Set("PJSIP/1001_7-00000026", "CALLED=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:26] Set("PJSIP/1001_7-00000026", "TRUNK_ENDPOINT=beaugast") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:27] Set("PJSIP/1001_7-00000026", "__CIDNUM=15024105444") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:28] Set("PJSIP/1001_7-00000026", "__CIDNAME=Wildlife Control") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:29] Set("PJSIP/1001_7-00000026", "FORCE_RECORDING=no") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:30] Set("PJSIP/1001_7-00000026", "FILTERED_NUMBER=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:31] GotoIf("PJSIP/1001_7-00000026", "0?:post-fax") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (trk-2-dial,5023907387,34)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:34] NoOp("PJSIP/1001_7-00000026", "End Fax Section") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:35] Dial("PJSIP/1001_7-00000026", "SIP/beaugast/5023907387,90,kKtrU(sub-call-recording^787f6b541ee875e3^1001^5023907387^no)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] netsock2.c: Using SIP RTP TOS bits 184
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] netsock2.c: Using SIP RTP CoS mark 5
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] app_dial.c: Called SIP/beaugast/5023907387
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:36] GotoIf("PJSIP/1001_7-00000026", "0?:after-fax") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (trk-2-dial,5023907387,39)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:39] NoOp("PJSIP/1001_7-00000026", "End Fax Section") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:40] NoOp("PJSIP/1001_7-00000026", "After Dial") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:41] GotoIf("PJSIP/1001_7-00000026", "1?come-back") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (trk-2-dial,5023907387,43)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2-dial:43] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2:3] GotoIf("PJSIP/1001_7-00000026", "1?come-back") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (trk-2,5023907387,5)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-2:5] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-1@cos-all-post:4] NoOp("PJSIP/1001_7-00000026", "Hangup Cause: 1, Dial Status: CHANUNAVAIL") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-1@cos-all-post:5] GotoIf("PJSIP/1001_7-00000026", "0?finish") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-1@cos-all-post:6] Gosub("PJSIP/1001_7-00000026", "trk-5,5023907387,1(from-trk-grp)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5:1] Set("PJSIP/1001_7-00000026", "_NTD=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5:2] Gosub("PJSIP/1001_7-00000026", "trk-5-dial,5023907387,1(from-trk-grp)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:1] NoOp("PJSIP/1001_7-00000026", "Trunk: VOIP.ms Main Account") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:2] NoOp("PJSIP/1001_7-00000026", "Dialing: 5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:3] Set("PJSIP/1001_7-00000026", "CDR(trunk)=5") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:4] Set("PJSIP/1001_7-00000026", "SKIP_HANGUP_AUDIOS=no") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:5] Gosub("PJSIP/1001_7-00000026", "sub-setup-call-type,s,1(outgoing,no)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:1] NoOp("PJSIP/1001_7-00000026", "Determinating Call Type") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:2] GotoIf("PJSIP/1001_7-00000026", "0?return") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:3] Gosub("PJSIP/1001_7-00000026", "s-outgoing,1(no)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:1] NoOp("PJSIP/1001_7-00000026", "Outgoing Call") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:2] Set("PJSIP/1001_7-00000026", "__CALL_TYPE=3") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:3] Set("PJSIP/1001_7-00000026", "__CALL_TYPE_LABEL=OUT") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:4] GosubIf("PJSIP/1001_7-00000026", "0?s-transit,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s-outgoing@sub-setup-call-type:5] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:4] Set("PJSIP/1001_7-00000026", "__CALL_TYPE_CONFIGURED=yes") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:5] Set("PJSIP/1001_7-00000026", "CDR(calltype)=3") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-setup-call-type:6] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:6] ExecIf("PJSIP/1001_7-00000026", "0?Set(CALLERID(all)=):") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:7] GotoIf("PJSIP/1001_7-00000026", "0?:after-val-sim-calls") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (trk-5-dial,5023907387,18)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:18] NoOp("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:19] NoOp("PJSIP/1001_7-00000026", "Configuring Trunk Parameters") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:20] ExecIf("PJSIP/1001_7-00000026", "0?Set(CHANNEL(musicclass)=default):") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:21] Set("PJSIP/1001_7-00000026", "__TRUNK_MOH_SET=yes") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:22] Set("PJSIP/1001_7-00000026", "__TRKDIALOPTIONS=kKtr") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:23] Set("PJSIP/1001_7-00000026", "ORIGINATOR=1001") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:24] Set("PJSIP/1001_7-00000026", "RING_TIME=90") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:25] Set("PJSIP/1001_7-00000026", "CALLED=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:26] Set("PJSIP/1001_7-00000026", "TRUNK_ENDPOINT=voipmspjsip") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:27] Set("PJSIP/1001_7-00000026", "__CIDNUM=15024105444") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:28] Set("PJSIP/1001_7-00000026", "__CIDNAME=Wildlife Control") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:29] Set("PJSIP/1001_7-00000026", "FORCE_RECORDING=no") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:30] Set("PJSIP/1001_7-00000026", "FILTERED_NUMBER=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:31] GotoIf("PJSIP/1001_7-00000026", "0?:post-fax") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (trk-5-dial,5023907387,34)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:34] NoOp("PJSIP/1001_7-00000026", "End Fax Section") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [5023907387@trk-5-dial:35] Dial("PJSIP/1001_7-00000026", "PJSIP/5023907387@voipmspjsip,90,kKtrU(sub-call-recording^787f6b541ee875e3^1001^5023907387^no)") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] app_dial.c: Called PJSIP/5023907387@voipmspjsip
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] app_dial.c: PJSIP/voipmspjsip-00000027 answered PJSIP/1001_7-00000026
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] app_stack.c: PJSIP/voipmspjsip-00000027 Internal Gosub(sub-call-recording,s,1(787f6b541ee875e3,1001,5023907387,no)) start
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:1] NoOp("PJSIP/voipmspjsip-00000027", "Check if call will be recorded on tenant 787f6b541ee875e3") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:2] NoOp("PJSIP/voipmspjsip-00000027", "Call Type: 3 ") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:3] Set("PJSIP/voipmspjsip-00000027", "TENANT=787f6b541ee875e3") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:4] Set("PJSIP/voipmspjsip-00000027", "CALLER=1001") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:5] Set("PJSIP/voipmspjsip-00000027", "CALLEE=5023907387") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:6] Set("PJSIP/voipmspjsip-00000027", "APP_FORCE=no") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:7] GotoIf("PJSIP/voipmspjsip-00000027", "1?:rec_no_available") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:8] GotoIf("PJSIP/voipmspjsip-00000027", "0?perform-recording") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:9] GotoIf("PJSIP/voipmspjsip-00000027", "0?perform-recording") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:10] GotoIf("PJSIP/voipmspjsip-00000027", "0?perform-recording") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:11] GotoIf("PJSIP/voipmspjsip-00000027", "0?perform-recording") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:12] GotoIf("PJSIP/voipmspjsip-00000027", "1?perform-recording") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (sub-call-recording,s,14)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:14] Gosub("PJSIP/voipmspjsip-00000027", "sub-perform-recording,s,1") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-perform-recording:1] NoOp("PJSIP/voipmspjsip-00000027", "Perform Call Recording") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-perform-recording:2] Set("PJSIP/voipmspjsip-00000027", "RECORDING_ID=1636342710.237") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-perform-recording:3] Set("PJSIP/voipmspjsip-00000027", "APP_TYPE=NONE") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-perform-recording:4] Set("PJSIP/voipmspjsip-00000027", "AUDIO_FILE=223830-OUT-NONE-1001-5023907387-1636342710.237") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-perform-recording:5] Set("PJSIP/voipmspjsip-00000027", "RECORDING_FORMAT=wav") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-perform-recording:6] Set("PJSIP/voipmspjsip-00000027", "__REC_FILENAME=/var/spool/asterisk/monitor/2021/11/07/223830-OUT-NONE-1001-5023907387-1636342710.237.wav") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-perform-recording:7] GotoIf("PJSIP/voipmspjsip-00000027", "0?:set_rec_file") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (sub-perform-recording,s,10)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-perform-recording:10] Set("PJSIP/voipmspjsip-00000027", "CDR(recfile)=/var/spool/asterisk/monitor/2021/11/07/223830-OUT-NONE-1001-5023907387-1636342710.237.wav") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-perform-recording:11] MixMonitor("PJSIP/voipmspjsip-00000027", "/var/spool/asterisk/monitor/2021/11/07/223830-OUT-NONE-1001-5023907387-1636342710.237.wav,b,") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-perform-recording:12] Return("PJSIP/voipmspjsip-00000027", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:15] Goto("PJSIP/voipmspjsip-00000027", "return") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (sub-call-recording,s,17)
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] pbx.c: Executing [s@sub-call-recording:17] Return("PJSIP/voipmspjsip-00000027", "") in new stack
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] app_stack.c: Spawn extension (trk-5-in, , 1) exited non-zero on 'PJSIP/voipmspjsip-00000027'
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] app_stack.c: PJSIP/voipmspjsip-00000027 Internal Gosub(sub-call-recording,s,1(787f6b541ee875e3,1001,5023907387,no)) complete GOSUB_RETVAL=
[2021-11-07 22:38:30] VERBOSE[7909][C-00000015] app_mixmonitor.c: Begin MixMonitor Recording PJSIP/voipmspjsip-00000027
[2021-11-07 22:38:30] VERBOSE[7910][C-00000015] bridge_channel.c: Channel PJSIP/voipmspjsip-00000027 joined 'simple_bridge' basic-bridge <01e63500-6de6-4abb-88cf-3d3710af7d3c>
[2021-11-07 22:38:30] VERBOSE[7906][C-00000015] bridge_channel.c: Channel PJSIP/1001_7-00000026 joined 'simple_bridge' basic-bridge <01e63500-6de6-4abb-88cf-3d3710af7d3c>
[2021-11-07 22:38:32] VERBOSE[7910][C-00000015] bridge_channel.c: Channel PJSIP/voipmspjsip-00000027 left 'simple_bridge' basic-bridge <01e63500-6de6-4abb-88cf-3d3710af7d3c>
[2021-11-07 22:38:32] VERBOSE[7909][C-00000015] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2021-11-07 22:38:32] VERBOSE[7909][C-00000015] app_mixmonitor.c: End MixMonitor Recording PJSIP/voipmspjsip-00000027
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] bridge_channel.c: Channel PJSIP/1001_7-00000026 left 'simple_bridge' basic-bridge <01e63500-6de6-4abb-88cf-3d3710af7d3c>
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Spawn extension (trk-5-dial, 5023907387, 35) exited non-zero on 'PJSIP/1001_7-00000026'
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [h@trk-5-dial:1] Gosub("PJSIP/1001_7-00000026", "dynamic-routing-out,s,1(1001,5023907387,ANSWER,5)") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@dynamic-routing-out:1] NoOp("PJSIP/1001_7-00000026", "Test if save on dynamic routing list") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@dynamic-routing-out:2] Set("PJSIP/1001_7-00000026", "CALLER=1001") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@dynamic-routing-out:3] Set("PJSIP/1001_7-00000026", "CALLED=5023907387") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@dynamic-routing-out:4] Set("PJSIP/1001_7-00000026", "STATUS=ANSWER") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@dynamic-routing-out:5] Set("PJSIP/1001_7-00000026", "TRKID=5") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@dynamic-routing-out:6] Set("PJSIP/1001_7-00000026", "ALLOW_DR=no") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@dynamic-routing-out:7] Set("PJSIP/1001_7-00000026", "DYNROUTING_OMC=yes") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@dynamic-routing-out:8] GotoIf("PJSIP/1001_7-00000026", "0?:comeback") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx_builtins.c: Goto (dynamic-routing-out,s,10)
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@dynamic-routing-out:10] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [h@trk-5-dial:2] Gosub("PJSIP/1001_7-00000026", "notify-trunk-hangup,s,1") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@notify-trunk-hangup:1] Verbose("PJSIP/1001_7-00000026", "0, Trunk Hangup") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] app_verbose.c: Trunk Hangup
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@notify-trunk-hangup:2] UserEvent("PJSIP/1001_7-00000026", ""EXTENSIONS_SUMMARY","Data: OUTBOUND,PJSIP/1001_7,5023907387,OUT"") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [s@notify-trunk-hangup:3] Return("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Executing [h@trk-5-dial:3] Hangup("PJSIP/1001_7-00000026", "") in new stack
[2021-11-07 22:38:32] VERBOSE[7906][C-00000015] pbx.c: Spawn extension (trk-5-dial, h, 3) exited non-zero on 'PJSIP/1001_7-00000026'
VitalPBX 3.1.2-1
CentOS Linux release 7.9.2009 (Core)
Kernel 3.10.0-1160.45.1.el7.x86_64
Asterisk 18.6.0
Have updated via yum upgrade routinely and all upgrades applied.