Dropped calls to DID

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.

Hey @Beau_G,

Call from 1001 to 5023907387 uses the outbound route “Outbound Calls.” This route has a trunk called “PLIVO/beaugast,” which rejects the call with the status “busy/congested.” The Caller ID used during this call is "Wildlife Control" <15024105444>.

Finally, the call overflows to the trunk “VOIP.ms Main Account/voipmspjsip.” Here, the call seems to be answered and hung up a couple of seconds later.

I don’t see anything related to the transfer feature. Moreover, VoIP.ms has been experiencing a lot of issues lately.

This topic was automatically closed 30 days after the last reply. New replies are no longer allowed.