.call files always get routed to voicemail

Trying to build a reminders application using .call files, but even the simplest .call file fails to ring a working PJsip extension (501). Code below. What am I missing??

Channel: local/501@cos-all
Callerid: "Reminder <123>"
MaxRetries: 1
RetryTime: 10
WaitTime: 5
Account: Reminder
context: remindem
extension: s
priority: 1

[remindem]
exten => s,1,Answer
exten => s,2,Wait(2)
exten => s,3,Set(TIMEOUT(digit)=7)
exten => s,4,Set(TIMEOUT(response)=10)
exten => s,5,Playback(custom/reminder5)
exten => s,6,Wait(2)
exten => s,7,Playback(custom/${MSG})
exten => s,8,Set(TIMEOUT(digit)=4)
exten => s,9,Set(TIMEOUT(response)=5)
exten => s,10,Set(counter=1)
exten => s,11,Background(to-hear-msg-again)
exten => s,12,Background(press-1)
exten => s,13,Background(otherwise-press)
exten => s,14,Read(lepick,digits/2,1)
exten => s,15,GotoIf($[β€œ${lepick}” = β€œ2”]?alldone,1:onemo,1)

    -- Attempting call on local/501@cos-all for s@remindem:1 (Retry 1)
    -- Called 501@cos-all
    -- Executing [501@cos-all:1] NoOp("Local/501@cos-all-0000001c;2", "More than on digit pattern") in new stack
    -- Executing [501@cos-all:2] Gosub("Local/501@cos-all-0000001c;2", "s,1(501)") in new stack
    -- Executing [s@cos-all:1] Set("Local/501@cos-all-0000001c;2", "EXTENSION=501") in new stack
    -- Executing [s@cos-all:2] NoOp("Local/501@cos-all-0000001c;2", "Dialing 501 from 123") in new stack
    -- Executing [s@cos-all:3] Gosub("Local/501@cos-all-0000001c;2", "sub-set-global-vars,s,1(abcb300339ea510e,501,parking-1)") in new stack
    -- Executing [s@sub-set-global-vars:1] NoOp("Local/501@cos-all-0000001c;2", "Setting Global Vars") in new stack
    -- Executing [s@sub-set-global-vars:2] Set("Local/501@cos-all-0000001c;2", "TENANT_PATH=abcb300339ea510e") in new stack
    -- Executing [s@sub-set-global-vars:3] Set("Local/501@cos-all-0000001c;2", "PREFIX=") in new stack
    -- Executing [s@sub-set-global-vars:4] Set("Local/501@cos-all-0000001c;2", "__PICKUPMARK=501") in new stack
    -- Executing [s@sub-set-global-vars:5] Set("Local/501@cos-all-0000001c;2", "__PARKINGLOT=parking-1") in new stack
    -- Executing [s@sub-set-global-vars:6] Set("Local/501@cos-all-0000001c;2", "__DYNAMIC_FEATURES=custom-features") in new stack
    -- Executing [s@sub-set-global-vars:7] Set("Local/501@cos-all-0000001c;2", "ICALL=no") in new stack
    -- Executing [s@sub-set-global-vars:8] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@cos-all:4] Gosub("Local/501@cos-all-0000001c;2", "sub-set-call-vars,s,1(abcb300339ea510e,501,1,cos-all,ARS-all)") in new stack
    -- Executing [s@sub-set-call-vars:1] NoOp("Local/501@cos-all-0000001c;2", "Setting Call Vars") in new stack
    -- Executing [s@sub-set-call-vars:2] Set("Local/501@cos-all-0000001c;2", "_TENANT=abcb300339ea510e") in new stack
    -- Executing [s@sub-set-call-vars:3] Set("Local/501@cos-all-0000001c;2", "_CALL_DESTINATION=501") in new stack
    -- Executing [s@sub-set-call-vars:4] Set("Local/501@cos-all-0000001c;2", "_COS_ID=1") in new stack
    -- Executing [s@sub-set-call-vars:5] Set("Local/501@cos-all-0000001c;2", "_COS_NAME=cos-all") in new stack
    -- Executing [s@sub-set-call-vars:6] Set("Local/501@cos-all-0000001c;2", "__TRANSFER_CONTEXT=cos-all") in new stack
    -- Executing [s@sub-set-call-vars:7] Set("Local/501@cos-all-0000001c;2", "_ARS=ARS-all") in new stack
    -- Executing [s@sub-set-call-vars:8] Set("Local/501@cos-all-0000001c;2", "CDR(tenant)=vitalpbx") in new stack
    -- Executing [s@sub-set-call-vars:9] Set("Local/501@cos-all-0000001c;2", "CDR(destination)=501") in new stack
    -- Executing [s@sub-set-call-vars:10] Set("Local/501@cos-all-0000001c;2", "CDR(trunk)=") in new stack
    -- Executing [s@sub-set-call-vars:11] Set("Local/501@cos-all-0000001c;2", "CDR(did)=") in new stack
    -- Executing [s@sub-set-call-vars:12] Set("Local/501@cos-all-0000001c;2", "__CALLID=1690833279.80") in new stack
    -- Executing [s@sub-set-call-vars:13] NoOp("Local/501@cos-all-0000001c;2", "Channel Type: Local") in new stack
[2023-07-31 15:54:39] WARNING[55529][C-00000033]: func_channel.c:527 func_channel_read: Unknown or unavailable item requested: 'pjsip,call-id'
    -- Executing [s@sub-set-call-vars:14] ExecIf("Local/501@cos-all-0000001c;2", "0?Set(__PJSIP_CALLID=)") in new stack
    -- Executing [s@sub-set-call-vars:15] ExecIf("Local/501@cos-all-0000001c;2", "0?Set(CHANNEL(language)=)") in new stack
    -- Executing [s@sub-set-call-vars:16] ExecIf("Local/501@cos-all-0000001c;2", "0?Set(CHANNEL(language)=)") in new stack
    -- Executing [s@sub-set-call-vars:17] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@cos-all:5] Gosub("Local/501@cos-all-0000001c;2", "sub-construct-cid,s,1") in new stack
    -- Executing [s@sub-construct-cid:1] NoOp("Local/501@cos-all-0000001c;2", "Building CID") in new stack
    -- Executing [s@sub-construct-cid:2] Set("Local/501@cos-all-0000001c;2", "ORIGINAL_CID_NAME=Reminder") in new stack
    -- Executing [s@sub-construct-cid:3] Set("Local/501@cos-all-0000001c;2", "ORIGINAL_CID_NUMBER=123") in new stack
    -- Executing [s@sub-construct-cid:4] Set("Local/501@cos-all-0000001c;2", "_CALL_SOURCE=123") in new stack
    -- Executing [s@sub-construct-cid:5] Set("Local/501@cos-all-0000001c;2", "_CS=123") in new stack
    -- Executing [s@sub-construct-cid:6] GotoIf("Local/501@cos-all-0000001c;2", "0?return") in new stack
    -- Executing [s@sub-construct-cid:7] Gosub("Local/501@cos-all-0000001c;2", "sub-get-device-tree,s,1") in new stack
    -- Executing [s@sub-get-device-tree:1] NoOp("Local/501@cos-all-0000001c;2", "Configuring Device Tree") in new stack
    -- Executing [s@sub-get-device-tree:2] Set("Local/501@cos-all-0000001c;2", "DEV_TECHNOLOGY=Local") in new stack
    -- Executing [s@sub-get-device-tree:3] GosubIf("Local/501@cos-all-0000001c;2", "1?s-local,1") in new stack
    -- Executing [s-local@sub-get-device-tree:1] NoOp("Local/501@cos-all-0000001c;2", "Try to determianate caller technology 123") in new stack
    -- Executing [s-local@sub-get-device-tree:2] Set("Local/501@cos-all-0000001c;2", "TECH_DETERMINATED=no") in new stack
[2023-07-31 15:54:39] WARNING[55529][C-00000033]: func_channel.c:527 func_channel_read: Unknown or unavailable item requested: 'dahdi_channel'
    -- Executing [s-local@sub-get-device-tree:3] Set("Local/501@cos-all-0000001c;2", "DEV_TECHNOLOGY=Local") in new stack
    -- Executing [s-local@sub-get-device-tree:4] Set("Local/501@cos-all-0000001c;2", "DEV_TECHNOLOGY=Local") in new stack
    -- Executing [s-local@sub-get-device-tree:5] Set("Local/501@cos-all-0000001c;2", "DEV_TECHNOLOGY=Local") in new stack
    -- Executing [s-local@sub-get-device-tree:6] Set("Local/501@cos-all-0000001c;2", "TECH_DETERMINATED=no") in new stack
    -- Executing [s-local@sub-get-device-tree:7] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@sub-get-device-tree:4] GotoIf("Local/501@cos-all-0000001c;2", "1?return") in new stack
    -- Goto (sub-get-device-tree,s,15)
    -- Executing [s@sub-get-device-tree:15] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@sub-construct-cid:8] GotoIf("Local/501@cos-all-0000001c;2", "1?return") in new stack
    -- Goto (sub-construct-cid,s,16)
    -- Executing [s@sub-construct-cid:16] NoOp("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@sub-construct-cid:17] Set("Local/501@cos-all-0000001c;2", "CDR(source)=123") in new stack
    -- Executing [s@sub-construct-cid:18] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@cos-all:6] Gosub("Local/501@cos-all-0000001c;2", "set-global-tenant-vars,s,1") in new stack
    -- Executing [s@set-global-tenant-vars:1] NoOp("Local/501@cos-all-0000001c;2", "Setting Global Vars for vitalpbx Tenant") in new stack
    -- Executing [s@set-global-tenant-vars:2] Set("Local/501@cos-all-0000001c;2", "__TENANT_PATH=abcb300339ea510e") in new stack
    -- Executing [s@set-global-tenant-vars:3] Set("Local/501@cos-all-0000001c;2", "__TENANT_PREFIX=") in new stack
    -- Executing [s@set-global-tenant-vars:4] Set("Local/501@cos-all-0000001c;2", "__QUEUE_AGENTS_CONTEXT=queue-call-to-agents") in new stack
    -- Executing [s@set-global-tenant-vars:5] Set("Local/501@cos-all-0000001c;2", "__FOLLOWME_CONTEXT=ext-followme") in new stack
    -- Executing [s@set-global-tenant-vars:6] Set("Local/501@cos-all-0000001c;2", "__HINTS_CONTEXT=extension-hints") in new stack
    -- Executing [s@set-global-tenant-vars:7] Set("Local/501@cos-all-0000001c;2", "__DEFAULT_COS=cos-all") in new stack
    -- Executing [s@set-global-tenant-vars:8] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@cos-all:7] GotoIf("Local/501@cos-all-0000001c;2", "0?local-dialing") in new stack
    -- Executing [s@cos-all:8] NoOp("Local/501@cos-all-0000001c;2", "Check if is an Emergency Call") in new stack
    -- Executing [s@cos-all:9] GotoIf("Local/501@cos-all-0000001c;2", "0?emergency-calls,501,1") in new stack
    -- Executing [s@cos-all:10] Gosub("Local/501@cos-all-0000001c;2", "sub-lockphone-check,s,1") in new stack
    -- Executing [s@sub-lockphone-check:1] NoOp("Local/501@cos-all-0000001c;2", "SUB: LOCK PHONE CHECK") in new stack
    -- Executing [s@sub-lockphone-check:2] GotoIf("Local/501@cos-all-0000001c;2", "0?unlock") in new stack
    -- Executing [s@sub-lockphone-check:3] GotoIf("Local/501@cos-all-0000001c;2", "0?unlock") in new stack
    -- Executing [s@sub-lockphone-check:4] GotoIf("Local/501@cos-all-0000001c;2", "0?unlock") in new stack
    -- Executing [s@sub-lockphone-check:5] GotoIf("Local/501@cos-all-0000001c;2", "0?unlock") in new stack
    -- Executing [s@sub-lockphone-check:6] GotoIf("Local/501@cos-all-0000001c;2", "0?:unlock") in new stack
    -- Goto (sub-lockphone-check,s,12)
    -- Executing [s@sub-lockphone-check:12] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@cos-all:11] Gosub("Local/501@cos-all-0000001c;2", "sub-local-dialing,s,1") in new stack
    -- Executing [s@sub-local-dialing:1] NoOp("Local/501@cos-all-0000001c;2", "Trying to determine if is a local extension") in new stack
    -- Executing [s@sub-local-dialing:2] GotoIf("Local/501@cos-all-0000001c;2", "1?:return") in new stack
    -- Executing [s@sub-local-dialing:3] Set("Local/501@cos-all-0000001c;2", "EXTENSION_TO_DIAL=501") in new stack
    -- Executing [s@sub-local-dialing:4] Goto("Local/501@cos-all-0000001c;2", "501,1") in new stack
    -- Goto (sub-local-dialing,501,1)
    -- Executing [501@sub-local-dialing:1] NoOp("Local/501@cos-all-0000001c;2", "Dialing Local Extension") in new stack
    -- Executing [501@sub-local-dialing:2] ExecIf("Local/501@cos-all-0000001c;2", "0?NoCDR()") in new stack
    -- Executing [501@sub-local-dialing:3] Gosub("Local/501@cos-all-0000001c;2", "sub-setup-call-type,s,1(internal)") in new stack
    -- Executing [s@sub-setup-call-type:1] NoOp("Local/501@cos-all-0000001c;2", "Determinating Call Type") in new stack
    -- Executing [s@sub-setup-call-type:2] GotoIf("Local/501@cos-all-0000001c;2", "0?return") in new stack
    -- Executing [s@sub-setup-call-type:3] Gosub("Local/501@cos-all-0000001c;2", "s-internal,1()") in new stack
    -- Executing [s-internal@sub-setup-call-type:1] NoOp("Local/501@cos-all-0000001c;2", "Internal Call") in new stack
    -- Executing [s-internal@sub-setup-call-type:2] Set("Local/501@cos-all-0000001c;2", "__CALL_TYPE=1") in new stack
    -- Executing [s-internal@sub-setup-call-type:3] Set("Local/501@cos-all-0000001c;2", "__CALL_TYPE_LABEL=LOCAL") in new stack
    -- Executing [s-internal@sub-setup-call-type:4] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@sub-setup-call-type:4] Set("Local/501@cos-all-0000001c;2", "__CALL_TYPE_CONFIGURED=yes") in new stack
    -- Executing [s@sub-setup-call-type:5] Set("Local/501@cos-all-0000001c;2", "CDR(calltype)=1") in new stack
    -- Executing [s@sub-setup-call-type:6] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [501@sub-local-dialing:4] Set("Local/501@cos-all-0000001c;2", "_DESTINATION_STATE=UNAVAILABLE") in new stack
    -- Executing [501@sub-local-dialing:5] Set("Local/501@cos-all-0000001c;2", "_DESTINATION_NUMBER=501") in new stack
    -- Executing [501@sub-local-dialing:6] Set("Local/501@cos-all-0000001c;2", "_DESTINATION_COS_NAME=cos-all") in new stack
    -- Executing [501@sub-local-dialing:7] Gosub("Local/501@cos-all-0000001c;2", "sub-check-cos-privacy,s,1(cos-all,1,cos-all)") in new stack
    -- Executing [s@sub-check-cos-privacy:1] NoOp("Local/501@cos-all-0000001c;2", "Checking Privacy of CoS: cos-all") in new stack
    -- Executing [s@sub-check-cos-privacy:2] GotoIf("Local/501@cos-all-0000001c;2", "1?:no_local") in new stack
    -- Executing [s@sub-check-cos-privacy:3] Set("Local/501@cos-all-0000001c;2", "CALLER_COS_ID=1") in new stack
    -- Executing [s@sub-check-cos-privacy:4] Set("Local/501@cos-all-0000001c;2", "CALLER_COS_NAME=cos-all") in new stack
    -- Executing [s@sub-check-cos-privacy:5] Set("Local/501@cos-all-0000001c;2", "CALLED_COS_ID=1") in new stack
    -- Executing [s@sub-check-cos-privacy:6] GotoIf("Local/501@cos-all-0000001c;2", "1?same_cos") in new stack
    -- Goto (sub-check-cos-privacy,s,17)
    -- Executing [s@sub-check-cos-privacy:17] NoOp("Local/501@cos-all-0000001c;2", "The caller CoS and Callee CoS are the same") in new stack
    -- Executing [s@sub-check-cos-privacy:18] Goto("Local/501@cos-all-0000001c;2", "return") in new stack
    -- Goto (sub-check-cos-privacy,s,22)
    -- Executing [s@sub-check-cos-privacy:22] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [501@sub-local-dialing:8] Set("Local/501@cos-all-0000001c;2", "DIAL_STRING=PJSIP/501") in new stack
    -- Executing [501@sub-local-dialing:9] Set("Local/501@cos-all-0000001c;2", "DB(abcb300339ea510e/LASTCALLER/501)=123") in new stack
    -- Executing [501@sub-local-dialing:10] Set("Local/501@cos-all-0000001c;2", "IGNORE_DIVERSIONS=") in new stack
    -- Executing [501@sub-local-dialing:11] Set("Local/501@cos-all-0000001c;2", "QUEUE_CALL=") in new stack
    -- Executing [501@sub-local-dialing:12] GosubIf("Local/501@cos-all-0000001c;2", "0?sub-queue-members,s,1") in new stack
    -- Executing [501@sub-local-dialing:13] GosubIf("Local/501@cos-all-0000001c;2", "0?:sub-check-diversions,s,1(predialing)") in new stack
    -- Executing [s@sub-check-diversions:1] NoOp("Local/501@cos-all-0000001c;2", "Checking diversions for extension 501") in new stack
    -- Executing [s@sub-check-diversions:2] Set("Local/501@cos-all-0000001c;2", "OLD_CALLER_ID="Reminder" <123>") in new stack
    -- Executing [s@sub-check-diversions:3] GosubIf("Local/501@cos-all-0000001c;2", "0?:return") in new stack
    -- Executing [s@sub-check-diversions:12] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@sub-check-diversions:4] Set("Local/501@cos-all-0000001c;2", "EXTENSION_INTERNAL_CID="501" <501>") in new stack
    -- Executing [s@sub-check-diversions:5] Set("Local/501@cos-all-0000001c;2", "CID_ON_DIVERSIONS=callee") in new stack
    -- Executing [s@sub-check-diversions:6] ExecIf("Local/501@cos-all-0000001c;2", "1?Set(CALLERID(all)="501" <501>)") in new stack
    -- Executing [s@sub-check-diversions:7] ExecIf("Local/501@cos-all-0000001c;2", "0?Set(__EXT_CID_CONSTRUCTED=yes)") in new stack
    -- Executing [s@sub-check-diversions:8] Set("Local/501@cos-all-0000001c;2", "__CALL_ORIGIN=diversions") in new stack
    -- Executing [s@sub-check-diversions:9] Set("Local/501@cos-all-0000001c;2", "__DIVERSION_ORIGIN=501") in new stack
    -- Executing [s@sub-check-diversions:10] Gosub("Local/501@cos-all-0000001c;2", "s-predialing,1") in new stack
    -- Executing [s-predialing@sub-check-diversions:1] NoOp("Local/501@cos-all-0000001c;2", "Diversions on pre-dialing") in new stack
    -- Executing [s-predialing@sub-check-diversions:2] GosubIf("Local/501@cos-all-0000001c;2", "0?:diversion-testing,1(Do not Disturb,DND,,,dnd)") in new stack
    -- Executing [diversion-testing@sub-check-diversions:1] NoOp("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [diversion-testing@sub-check-diversions:2] Set("Local/501@cos-all-0000001c;2", "FROM_NUMBER=501") in new stack
    -- Executing [diversion-testing@sub-check-diversions:3] GotoIf("Local/501@cos-all-0000001c;2", "0?:next-diversion") in new stack
    -- Goto (sub-check-diversions,diversion-testing,36)
    -- Executing [diversion-testing@sub-check-diversions:36] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s-predialing@sub-check-diversions:3] Gosub("Local/501@cos-all-0000001c;2", "diversion-testing,1(Call Forward Immeditely,CFI,,,toggle_cfi)") in new stack
    -- Executing [diversion-testing@sub-check-diversions:1] NoOp("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [diversion-testing@sub-check-diversions:2] Set("Local/501@cos-all-0000001c;2", "FROM_NUMBER=501") in new stack
    -- Executing [diversion-testing@sub-check-diversions:3] GotoIf("Local/501@cos-all-0000001c;2", "0?:next-diversion") in new stack
    -- Goto (sub-check-diversions,diversion-testing,36)
    -- Executing [diversion-testing@sub-check-diversions:36] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s-predialing@sub-check-diversions:4] GosubIf("Local/501@cos-all-0000001c;2", "0?:diversion-testing,1(Boss Secretray,BOSS,,,boss_secretary)") in new stack
    -- Executing [diversion-testing@sub-check-diversions:1] NoOp("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [diversion-testing@sub-check-diversions:2] Set("Local/501@cos-all-0000001c;2", "FROM_NUMBER=501") in new stack
    -- Executing [diversion-testing@sub-check-diversions:3] GotoIf("Local/501@cos-all-0000001c;2", "0?:next-diversion") in new stack
    -- Goto (sub-check-diversions,diversion-testing,36)
    -- Executing [diversion-testing@sub-check-diversions:36] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s-predialing@sub-check-diversions:5] GosubIf("Local/501@cos-all-0000001c;2", "0?:diversion-testing,1(Personal Assistant,PEA,sub-personal-assistant,s,1)") in new stack
    -- Executing [diversion-testing@sub-check-diversions:1] NoOp("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [diversion-testing@sub-check-diversions:2] Set("Local/501@cos-all-0000001c;2", "FROM_NUMBER=501") in new stack
    -- Executing [diversion-testing@sub-check-diversions:3] GotoIf("Local/501@cos-all-0000001c;2", "0?:next-diversion") in new stack
    -- Goto (sub-check-diversions,diversion-testing,36)
    -- Executing [diversion-testing@sub-check-diversions:36] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s-predialing@sub-check-diversions:6] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@sub-check-diversions:11] Set("Local/501@cos-all-0000001c;2", "CALLERID(all)="Reminder" <123>") in new stack
    -- Executing [s@sub-check-diversions:12] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [501@sub-local-dialing:14] GotoIf("Local/501@cos-all-0000001c;2", "0?post-dial") in new stack
    -- Executing [501@sub-local-dialing:15] Gosub("Local/501@cos-all-0000001c;2", "parse-dial-string,s,1") in new stack
    -- Executing [s@parse-dial-string:1] NoOp("Local/501@cos-all-0000001c;2", "Parsing dial string: PJSIP/501") in new stack
    -- Executing [s@parse-dial-string:2] Set("Local/501@cos-all-0000001c;2", "NEW_DIAL_STRING=") in new stack
    -- Executing [s@parse-dial-string:3] Set("Local/501@cos-all-0000001c;2", "COUNTER=1") in new stack
    -- Executing [s@parse-dial-string:4] Set("Local/501@cos-all-0000001c;2", "CURRENT_DEVICE=PJSIP/501") in new stack
    -- Executing [s@parse-dial-string:5] While("Local/501@cos-all-0000001c;2", "1") in new stack
    -- Executing [s@parse-dial-string:6] Set("Local/501@cos-all-0000001c;2", "TECHNOLOGY=PJSIP") in new stack
    -- Executing [s@parse-dial-string:7] Set("Local/501@cos-all-0000001c;2", "USER=501") in new stack
    -- Executing [s@parse-dial-string:8] GotoIf("Local/501@cos-all-0000001c;2", "1?:regular") in new stack
    -- Executing [s@parse-dial-string:9] Goto("Local/501@cos-all-0000001c;2", "regular-pjsip") in new stack
    -- Goto (parse-dial-string,s,12)
    -- Executing [s@parse-dial-string:12] Set("Local/501@cos-all-0000001c;2", "PARSED_DEV=") in new stack
    -- Executing [s@parse-dial-string:13] ExecIf("Local/501@cos-all-0000001c;2", "1?Set(PARSED_DEV=PJSIP/501)") in new stack
    -- Executing [s@parse-dial-string:14] Goto("Local/501@cos-all-0000001c;2", "append") in new stack
    -- Goto (parse-dial-string,s,16)
    -- Executing [s@parse-dial-string:16] GotoIf("Local/501@cos-all-0000001c;2", "1?:concatenate") in new stack
    -- Executing [s@parse-dial-string:17] Set("Local/501@cos-all-0000001c;2", "NEW_DIAL_STRING=PJSIP/501") in new stack
    -- Executing [s@parse-dial-string:18] Goto("Local/501@cos-all-0000001c;2", "next") in new stack
    -- Goto (parse-dial-string,s,21)
    -- Executing [s@parse-dial-string:21] Set("Local/501@cos-all-0000001c;2", "COUNTER=2") in new stack
    -- Executing [s@parse-dial-string:22] Set("Local/501@cos-all-0000001c;2", "CURRENT_DEVICE=") in new stack
    -- Executing [s@parse-dial-string:23] EndWhile("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@parse-dial-string:5] While("Local/501@cos-all-0000001c;2", "0") in new stack
    -- Executing [s@parse-dial-string:24] NoOp("Local/501@cos-all-0000001c;2", "New dialstring: PJSIP/501") in new stack
    -- Executing [s@parse-dial-string:25] Set("Local/501@cos-all-0000001c;2", "DIAL_STRING=PJSIP/501") in new stack
    -- Executing [s@parse-dial-string:26] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [501@sub-local-dialing:16] NoOp("Local/501@cos-all-0000001c;2", "Configuring Dial Settings") in new stack
    -- Executing [501@sub-local-dialing:17] Set("Local/501@cos-all-0000001c;2", "RING_TIME=30") in new stack
    -- Executing [501@sub-local-dialing:18] Set("Local/501@cos-all-0000001c;2", "RING_TIME=30") in new stack
    -- Executing [501@sub-local-dialing:19] Set("Local/501@cos-all-0000001c;2", "RING_TIME=30") in new stack
    -- Executing [501@sub-local-dialing:20] Set("Local/501@cos-all-0000001c;2", "RING_TIME=30") in new stack
    -- Executing [501@sub-local-dialing:21] Set("Local/501@cos-all-0000001c;2", "DIAL_OPTIONS=kKtr") in new stack
    -- Executing [501@sub-local-dialing:22] Set("Local/501@cos-all-0000001c;2", "DIAL_OPTIONS=kKtr") in new stack
    -- Executing [501@sub-local-dialing:23] GotoIf("Local/501@cos-all-0000001c;2", "0?:answermode") in new stack
    -- Goto (sub-local-dialing,501,27)
    -- Executing [501@sub-local-dialing:27] Set("Local/501@cos-all-0000001c;2", "ANSWER_MODE=disable") in new stack
    -- Executing [501@sub-local-dialing:28] Set("Local/501@cos-all-0000001c;2", "ANSWER_MODE=disable") in new stack
    -- Executing [501@sub-local-dialing:29] GotoIf("Local/501@cos-all-0000001c;2", "0?check-hd") in new stack
    -- Executing [501@sub-local-dialing:30] ExecIf("Local/501@cos-all-0000001c;2", "0?Set(EXEC_AA=yes)") in new stack
    -- Executing [501@sub-local-dialing:31] ExecIf("Local/501@cos-all-0000001c;2", "0?Set(EXEC_AA=yes)") in new stack
    -- Executing [501@sub-local-dialing:32] Set("Local/501@cos-all-0000001c;2", "IS_HOT_DESK=no") in new stack
    -- Executing [501@sub-local-dialing:33] Set("Local/501@cos-all-0000001c;2", "MOH_CLASS=default") in new stack
    -- Executing [501@sub-local-dialing:34] GosubIf("Local/501@cos-all-0000001c;2", "0?sub-set-moh,s,1(default)") in new stack
    -- Executing [501@sub-local-dialing:35] GotoIf("Local/501@cos-all-0000001c;2", "0?busy-here") in new stack
    -- Executing [501@sub-local-dialing:36] Set("Local/501@cos-all-0000001c;2", "FORCE_RECORDING=no") in new stack
    -- Executing [501@sub-local-dialing:37] Set("Local/501@cos-all-0000001c;2", "DIAL_OPTIONS=kKtr") in new stack
    -- Executing [501@sub-local-dialing:38] Set("Local/501@cos-all-0000001c;2", "DIAL_OPTIONS=kKtrb(sub-before-connecting-call^s^1(abcb300339ea510e^123^501^^))") in new stack
    -- Executing [501@sub-local-dialing:39] Set("Local/501@cos-all-0000001c;2", "DIAL_OPTIONS=kKtrb(sub-before-connecting-call^s^1(abcb300339ea510e^123^501^^))U(sub-before-bridging-call^abcb300339ea510e^123^501^no)") in new stack
    -- Executing [501@sub-local-dialing:40] Set("Local/501@cos-all-0000001c;2", "__TRANSFERED_CALL=TRUE") in new stack
    -- Executing [501@sub-local-dialing:41] NoOp("Local/501@cos-all-0000001c;2", "Enable Follow-Me Ring Time If needed") in new stack
    -- Executing [501@sub-local-dialing:42] GotoIf("Local/501@cos-all-0000001c;2", "0?:check-rt") in new stack
    -- Goto (sub-local-dialing,501,49)
    -- Executing [501@sub-local-dialing:49] GotoIf("Local/501@cos-all-0000001c;2", "0?post-dial") in new stack
    -- Executing [501@sub-local-dialing:50] GotoIf("Local/501@cos-all-0000001c;2", "0?end_call") in new stack
    -- Executing [501@sub-local-dialing:51] Gosub("Local/501@cos-all-0000001c;2", "sub-udpate-virtual-dev-state,s,1(501,BUSY)") in new stack
    -- Executing [s@sub-udpate-virtual-dev-state:1] NoOp("Local/501@cos-all-0000001c;2", "Update Virtual Devices State") in new stack
    -- Executing [s@sub-udpate-virtual-dev-state:2] Set("Local/501@cos-all-0000001c;2", "EXT_NUM=501") in new stack
    -- Executing [s@sub-udpate-virtual-dev-state:3] Set("Local/501@cos-all-0000001c;2", "NEW_STATUS=BUSY") in new stack
    -- Executing [s@sub-udpate-virtual-dev-state:4] Set("Local/501@cos-all-0000001c;2", "EXTENSION_ID=1") in new stack
       > Found no rows [SELECT `device_id` FROM ombu_devices WHERE extension_id='1' and technology='virtual' and tenant_id=(SELECT tenant_id from ombu_tenants WHERE path='abcb300339ea510e')]
    -- Executing [s@sub-udpate-virtual-dev-state:5] Set("Local/501@cos-all-0000001c;2", "ODBC_ID=") in new stack
    -- Executing [s@sub-udpate-virtual-dev-state:6] GotoIf("Local/501@cos-all-0000001c;2", "1?no_rows") in new stack
    -- Goto (sub-udpate-virtual-dev-state,s,14)
    -- Executing [s@sub-udpate-virtual-dev-state:14] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [501@sub-local-dialing:52] Gosub("Local/501@cos-all-0000001c;2", "sub-setup-callrec-name,s,1") in new stack
    -- Executing [s@sub-setup-callrec-name:1] NoOp("Local/501@cos-all-0000001c;2", "Setting up call recording file name for abcb300339ea510e") in new stack
    -- Executing [s@sub-setup-callrec-name:2] GotoIf("Local/501@cos-all-0000001c;2", "0?end") in new stack
    -- Executing [s@sub-setup-callrec-name:3] Set("Local/501@cos-all-0000001c;2", "RECORDING_ID=1690833279.80") in new stack
    -- Executing [s@sub-setup-callrec-name:4] Set("Local/501@cos-all-0000001c;2", "APP_TYPE=NONE") in new stack
    -- Executing [s@sub-setup-callrec-name:5] Set("Local/501@cos-all-0000001c;2", "AUDIO_FILE=155439-LOCAL-NONE-123-501-1690833279.80") in new stack
    -- Executing [s@sub-setup-callrec-name:6] Set("Local/501@cos-all-0000001c;2", "RECORDING_FORMAT=wav") in new stack
    -- Executing [s@sub-setup-callrec-name:7] Set("Local/501@cos-all-0000001c;2", "__REC_FILENAME=/var/spool/asterisk/monitor/2023/07/31/155439-LOCAL-NONE-123-501-1690833279.80.wav") in new stack
    -- Executing [s@sub-setup-callrec-name:8] GotoIf("Local/501@cos-all-0000001c;2", "0?:end") in new stack
    -- Goto (sub-setup-callrec-name,s,11)
    -- Executing [s@sub-setup-callrec-name:11] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [501@sub-local-dialing:53] Dial("Local/501@cos-all-0000001c;2", "PJSIP/501,30,kKtrb(sub-before-connecting-call^s^1(abcb300339ea510e^123^501^^))U(sub-before-bridging-call^abcb300339ea510e^123^501^no)") in new stack
[2023-07-31 15:54:39] ERROR[40270]: res_pjsip.c:852 ast_sip_create_dialog_uac: Endpoint '501': Could not create dialog to invalid URI '501'.  Is endpoint registered and reachable?
[2023-07-31 15:54:39] ERROR[40270]: chan_pjsip.c:2681 request: Failed to create outgoing session to endpoint '501'
[2023-07-31 15:54:39] NOTICE[55529][C-00000033]: app_dial.c:2707 dial_exec_full: Unable to create channel of type 'PJSIP' (cause 3 - No route to destination)
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [501@sub-local-dialing:54] Set("Local/501@cos-all-0000001c;2", "_CALL_STATUS=CHANUNAVAIL") in new stack
    -- Executing [501@sub-local-dialing:55] Set("Local/501@cos-all-0000001c;2", "_CALL_STATUS=CHANUNAVAIL") in new stack
    -- Executing [501@sub-local-dialing:56] Gosub("Local/501@cos-all-0000001c;2", "sub-udpate-virtual-dev-state,s,1(501,NOT_INUSE)") in new stack
    -- Executing [s@sub-udpate-virtual-dev-state:1] NoOp("Local/501@cos-all-0000001c;2", "Update Virtual Devices State") in new stack
    -- Executing [s@sub-udpate-virtual-dev-state:2] Set("Local/501@cos-all-0000001c;2", "EXT_NUM=501") in new stack
    -- Executing [s@sub-udpate-virtual-dev-state:3] Set("Local/501@cos-all-0000001c;2", "NEW_STATUS=NOT_INUSE") in new stack
    -- Executing [s@sub-udpate-virtual-dev-state:4] Set("Local/501@cos-all-0000001c;2", "EXTENSION_ID=1") in new stack
       > Found no rows [SELECT `device_id` FROM ombu_devices WHERE extension_id='1' and technology='virtual' and tenant_id=(SELECT tenant_id from ombu_tenants WHERE path='abcb300339ea510e')]
    -- Executing [s@sub-udpate-virtual-dev-state:5] Set("Local/501@cos-all-0000001c;2", "ODBC_ID=") in new stack
    -- Executing [s@sub-udpate-virtual-dev-state:6] GotoIf("Local/501@cos-all-0000001c;2", "1?no_rows") in new stack
    -- Goto (sub-udpate-virtual-dev-state,s,14)
    -- Executing [s@sub-udpate-virtual-dev-state:14] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [501@sub-local-dialing:57] Goto("Local/501@cos-all-0000001c;2", "check-post-diversions") in new stack
    -- Goto (sub-local-dialing,501,59)
    -- Executing [501@sub-local-dialing:59] GosubIf("Local/501@cos-all-0000001c;2", "0?:sub-check-diversions,s,1(postdialing)") in new stack
    -- Executing [s@sub-check-diversions:1] NoOp("Local/501@cos-all-0000001c;2", "Checking diversions for extension 501") in new stack
    -- Executing [s@sub-check-diversions:2] Set("Local/501@cos-all-0000001c;2", "OLD_CALLER_ID="Reminder" <123>") in new stack
    -- Executing [s@sub-check-diversions:3] GosubIf("Local/501@cos-all-0000001c;2", "0?:return") in new stack
    -- Executing [s@sub-check-diversions:12] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@sub-check-diversions:4] Set("Local/501@cos-all-0000001c;2", "EXTENSION_INTERNAL_CID="501" <501>") in new stack
    -- Executing [s@sub-check-diversions:5] Set("Local/501@cos-all-0000001c;2", "CID_ON_DIVERSIONS=callee") in new stack
    -- Executing [s@sub-check-diversions:6] ExecIf("Local/501@cos-all-0000001c;2", "1?Set(CALLERID(all)="501" <501>)") in new stack
    -- Executing [s@sub-check-diversions:7] ExecIf("Local/501@cos-all-0000001c;2", "0?Set(__EXT_CID_CONSTRUCTED=yes)") in new stack
    -- Executing [s@sub-check-diversions:8] Set("Local/501@cos-all-0000001c;2", "__CALL_ORIGIN=diversions") in new stack
    -- Executing [s@sub-check-diversions:9] Set("Local/501@cos-all-0000001c;2", "__DIVERSION_ORIGIN=501") in new stack
    -- Executing [s@sub-check-diversions:10] Gosub("Local/501@cos-all-0000001c;2", "s-postdialing,1") in new stack
    -- Executing [s-postdialing@sub-check-diversions:1] NoOp("Local/501@cos-all-0000001c;2", "Diversions on post-dialing") in new stack
    -- Executing [s-postdialing@sub-check-diversions:2] Gosub("Local/501@cos-all-0000001c;2", "diversion-testing,1(Follow Me,FWM,ext-followme,FW501,follow_me)") in new stack
    -- Executing [diversion-testing@sub-check-diversions:1] NoOp("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [diversion-testing@sub-check-diversions:2] Set("Local/501@cos-all-0000001c;2", "FROM_NUMBER=501") in new stack
    -- Executing [diversion-testing@sub-check-diversions:3] GotoIf("Local/501@cos-all-0000001c;2", "0?:next-diversion") in new stack
    -- Goto (sub-check-diversions,diversion-testing,36)
    -- Executing [diversion-testing@sub-check-diversions:36] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s-postdialing@sub-check-diversions:3] GosubIf("Local/501@cos-all-0000001c;2", "1?call-forward,1(CFU,toggle_cfu)") in new stack
    -- Executing [call-forward@sub-check-diversions:1] NoOp("Local/501@cos-all-0000001c;2", "CALL FOWARDING") in new stack
    -- Executing [call-forward@sub-check-diversions:2] Set("Local/501@cos-all-0000001c;2", "FROM_NUMBER=501") in new stack
    -- Executing [call-forward@sub-check-diversions:3] GotoIf("Local/501@cos-all-0000001c;2", "0?:return") in new stack
    -- Goto (sub-check-diversions,call-forward,14)
    -- Executing [call-forward@sub-check-diversions:14] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s-postdialing@sub-check-diversions:4] GosubIf("Local/501@cos-all-0000001c;2", "0?call-forward,1(CFU,toggle_cfu)") in new stack
    -- Executing [s-postdialing@sub-check-diversions:5] GosubIf("Local/501@cos-all-0000001c;2", "0?call-forward,1(CFB,toggle_cfb)") in new stack
    -- Executing [s-postdialing@sub-check-diversions:6] GosubIf("Local/501@cos-all-0000001c;2", "0?call-forward,1(CFN,toggle_cfn)") in new stack
    -- Executing [s-postdialing@sub-check-diversions:7] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [s@sub-check-diversions:11] Set("Local/501@cos-all-0000001c;2", "CALLERID(all)="Reminder" <123>") in new stack
    -- Executing [s@sub-check-diversions:12] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [501@sub-local-dialing:60] Gosub("Local/501@cos-all-0000001c;2", "sub-notify-missed-call,s,1(abcb300339ea510e,501,CHANUNAVAIL,123,Reminder,1,,)") in new stack
    -- Executing [s@sub-notify-missed-call:1] NoOp("Local/501@cos-all-0000001c;2", "Check if call is missed and need to be notified") in new stack
    -- Executing [s@sub-notify-missed-call:2] Set("Local/501@cos-all-0000001c;2", "TENANT=abcb300339ea510e") in new stack
    -- Executing [s@sub-notify-missed-call:3] Set("Local/501@cos-all-0000001c;2", "CALLEE_NUM=501") in new stack
    -- Executing [s@sub-notify-missed-call:4] Set("Local/501@cos-all-0000001c;2", "STATUS=CHANUNAVAIL") in new stack
    -- Executing [s@sub-notify-missed-call:5] Set("Local/501@cos-all-0000001c;2", "CALLER_NUM=123") in new stack
    -- Executing [s@sub-notify-missed-call:6] Set("Local/501@cos-all-0000001c;2", "CALLER_NAME=Reminder") in new stack
    -- Executing [s@sub-notify-missed-call:7] Set("Local/501@cos-all-0000001c;2", "CALL_DIR=1") in new stack
    -- Executing [s@sub-notify-missed-call:8] Set("Local/501@cos-all-0000001c;2", "DP_APP=") in new stack
    -- Executing [s@sub-notify-missed-call:9] Set("Local/501@cos-all-0000001c;2", "DP_APP_DESC=") in new stack
    -- Executing [s@sub-notify-missed-call:10] Set("Local/501@cos-all-0000001c;2", "IS_VM_ENABLED=yes") in new stack
    -- Executing [s@sub-notify-missed-call:11] GotoIf("Local/501@cos-all-0000001c;2", "0?notify_missed_call") in new stack
    -- Executing [s@sub-notify-missed-call:12] GotoIf("Local/501@cos-all-0000001c;2", "0?:last_stage") in new stack
    -- Goto (sub-notify-missed-call,s,15)
    -- Executing [s@sub-notify-missed-call:15] NoOp("Local/501@cos-all-0000001c;2", "Finished missed call notification test") in new stack
    -- Executing [s@sub-notify-missed-call:16] Return("Local/501@cos-all-0000001c;2", "") in new stack
    -- Executing [501@sub-local-dialing:61] Set("Local/501@cos-all-0000001c;2", "MISSED_CALL_NOTIFIED=TRUE") in new stack
    -- Executing [501@sub-local-dialing:62] GotoIf("Local/501@cos-all-0000001c;2", "0?end_call") in new stack
    -- Executing [501@sub-local-dialing:63] GotoIf("Local/501@cos-all-0000001c;2", "0?end_call") in new stack
    -- Executing [501@sub-local-dialing:64] GotoIf("Local/501@cos-all-0000001c;2", "0?end_call") in new stack
    -- Executing [501@sub-local-dialing:65] Gosub("Local/501@cos-all-0000001c;2", "sub-leave-vm,s,1") in new stack
    -- Executing [s@sub-leave-vm:1] NoOp("Local/501@cos-all-0000001c;2", "Leave Voicemail") in new stack
    -- Executing [s@sub-leave-vm:2] Set("Local/501@cos-all-0000001c;2", "VM_OPTIONS=u") in new stack
    -- Executing [s@sub-leave-vm:3] Set("Local/501@cos-all-0000001c;2", "VM_OPTIONS=u") in new stack
    -- Executing [s@sub-leave-vm:4] GotoIf("Local/501@cos-all-0000001c;2", "1?:vm_disabled") in new stack
    -- Executing [s@sub-leave-vm:5] Set("Local/501@cos-all-0000001c;2", "MAILBOX=501@vitalpbx-voicemail") in new stack
    -- Executing [s@sub-leave-vm:6] Answer("Local/501@cos-all-0000001c;2", "") in new stack
    -- Local/501@cos-all-0000001c;1 answered
    -- Executing [s@remindem:1] Answer("Local/501@cos-all-0000001c;1", "") in new stack
    -- Executing [s@remindem:2] Wait("Local/501@cos-all-0000001c;1", "2") in new stack
    -- Executing [s@sub-leave-vm:7] Gosub("Local/501@cos-all-0000001c;2", "sub-vm,s,1(501@vitalpbx-voicemail,u)") in new stack
    -- Executing [s@sub-vm:1] NoOp("Local/501@cos-all-0000001c;2", "Leave a Voicemail") in new stack
    -- Executing [s@sub-vm:2] Set("Local/501@cos-all-0000001c;2", "_VM_BOX=501@vitalpbx-voicemail") in new stack
    -- Executing [s@sub-vm:3] Set("Local/501@cos-all-0000001c;2", "VM_OPTIONS=u") in new stack
    -- Executing [s@sub-vm:4] Set("Local/501@cos-all-0000001c;2", "VM_GREETINGS_CTXT=sub-extvm-greetings") in new stack
    -- Executing [s@sub-vm:5] Set("Local/501@cos-all-0000001c;2", "VM_EXTEN=501") in new stack
    -- Executing [s@sub-vm:6] GotoIf("Local/501@cos-all-0000001c;2", "0?busy") in new stack
    -- Executing [s@sub-vm:7] GotoIf("Local/501@cos-all-0000001c;2", "0?unav") in new stack
    -- Executing [s@sub-vm:8] Goto("Local/501@cos-all-0000001c;2", "leave-vm") in new stack
    -- Goto (sub-vm,s,16)
    -- Executing [s@sub-vm:16] VoiceMail("Local/501@cos-all-0000001c;2", "501@vitalpbx-voicemail,u") in new stack
    -- <Local/501@cos-all-0000001c;2> Playing 'vm-theperson.ulaw' (language 'en')
    -- <Local/501@cos-all-0000001c;2> Playing 'digits/5.ulaw' (language 'en')
    -- <Local/501@cos-all-0000001c;2> Playing 'digits/0.ulaw' (language 'en')
    -- Executing [s@remindem:3] Set("Local/501@cos-all-0000001c;1", "TIMEOUT(digit)=7") in new stack
    -- Digit timeout set to 7.000
    -- Executing [s@remindem:4] Set("Local/501@cos-all-0000001c;1", "TIMEOUT(response)=10") in new stack
    -- Response timeout set to 10.000
    -- Executing [s@remindem:5] Playback("Local/501@cos-all-0000001c;1", "custom/reminder5") in new stack
    -- <Local/501@cos-all-0000001c;1> Playing 'custom/reminder5.gsm' (language 'en')
    -- <Local/501@cos-all-0000001c;2> Playing 'digits/1.ulaw' (language 'en')
    -- <Local/501@cos-all-0000001c;2> Playing 'vm-isunavail.ulaw' (language 'en')
    -- <Local/501@cos-all-0000001c;2> Playing 'vm-intro.ulaw' (language 'en')
    -- Executing [s@remindem:6] Wait("Local/501@cos-all-0000001c;1", "2") in new stack
    -- Executing [s@remindem:7] Playback("Local/501@cos-all-0000001c;1", "custom/") in new stack
[2023-07-31 15:54:46] WARNING[55528][C-00000034]: file.c:824 ast_openstream_full: File custom/ does not exist in any format
[2023-07-31 15:54:46] WARNING[55528][C-00000034]: file.c:1303 ast_streamfile: Unable to open custom/ (format (slin)): No such file or directory
[2023-07-31 15:54:46] WARNING[55528][C-00000034]: app_playback.c:512 playback_exec: Playback failed on Local/501@cos-all-0000001c;1 for custom/
    -- Executing [s@remindem:8] Set("Local/501@cos-all-0000001c;1", "TIMEOUT(digit)=4") in new stack
    -- Digit timeout set to 4.000
    -- Executing [s@remindem:9] Set("Local/501@cos-all-0000001c;1", "TIMEOUT(response)=5") in new stack
    -- Response timeout set to 5.000
    -- Executing [s@remindem:10] Set("Local/501@cos-all-0000001c;1", "counter=1") in new stack
    -- Executing [s@remindem:11] BackGround("Local/501@cos-all-0000001c;1", "to-hear-msg-again") in new stack
    -- <Local/501@cos-all-0000001c;1> Playing 'to-hear-msg-again.ulaw' (language 'en')
    -- Executing [s@remindem:12] BackGround("Local/501@cos-all-0000001c;1", "press-1") in new stack
    -- <Local/501@cos-all-0000001c;1> Playing 'press-1.ulaw' (language 'en')
    -- <Local/501@cos-all-0000001c;2> Playing 'beep.ulaw' (language 'en')
    -- Executing [s@remindem:13] BackGround("Local/501@cos-all-0000001c;1", "otherwise-press") in new stack
    -- <Local/501@cos-all-0000001c;1> Playing 'otherwise-press.ulaw' (language 'en')
    -- Recording the message
    -- x=0, open writing:  /var/spool/asterisk/voicemail/vitalpbx-voicemail/501/tmp/3ihQAD format: wav, 0x7f29f8015380
    -- Executing [s@remindem:14] Read("Local/501@cos-all-0000001c;1", "lepick,digits/2,1") in new stack
    -- Accepting a maximum of 1 digits.
    -- <Local/501@cos-all-0000001c;1> Playing 'digits/2.ulaw' (language 'en')
    -- User entered nothing.
    -- Executing [s@remindem:15] GotoIf("Local/501@cos-all-0000001c;1", "0?alldone,1:onemo,1") in new stack
    -- Goto (remindem,onemo,1)
    -- Executing [onemo@remindem:1] NoOp("Local/501@cos-all-0000001c;1", "Callee Replayed Message. Dialed: .") in new stack
    -- Executing [onemo@remindem:2] Playback("Local/501@cos-all-0000001c;1", "custom/") in new stack
[2023-07-31 15:54:54] WARNING[55528][C-00000034]: file.c:824 ast_openstream_full: File custom/ does not exist in any format
[2023-07-31 15:54:54] WARNING[55528][C-00000034]: file.c:1303 ast_streamfile: Unable to open custom/ (format (slin)): No such file or directory
[2023-07-31 15:54:54] WARNING[55528][C-00000034]: app_playback.c:512 playback_exec: Playback failed on Local/501@cos-all-0000001c;1 for custom/
    -- Executing [onemo@remindem:3] Set("Local/501@cos-all-0000001c;1", "counter=1+1") in new stack
    -- Executing [onemo@remindem:4] GotoIf("Local/501@cos-all-0000001c;1", "0?alldone,1:s,11") in new stack
    -- Goto (remindem,s,11)
    -- Executing [s@remindem:11] BackGround("Local/501@cos-all-0000001c;1", "to-hear-msg-again") in new stack
    -- <Local/501@cos-all-0000001c;1> Playing 'to-hear-msg-again.ulaw' (language 'en')
    -- Executing [s@remindem:12] BackGround("Local/501@cos-all-0000001c;1", "press-1") in new stack
    -- <Local/501@cos-all-0000001c;1> Playing 'press-1.ulaw' (language 'en')
    -- Executing [s@remindem:13] BackGround("Local/501@cos-all-0000001c;1", "otherwise-press") in new stack
    -- <Local/501@cos-all-0000001c;1> Playing 'otherwise-press.ulaw' (language 'en')
    -- Executing [s@remindem:14] Read("Local/501@cos-all-0000001c;1", "lepick,digits/2,1") in new stack
    -- Accepting a maximum of 1 digits.
    -- <Local/501@cos-all-0000001c;1> Playing 'digits/2.ulaw' (language 'en')
    -- User entered nothing.
    -- Executing [s@remindem:15] GotoIf("Local/501@cos-all-0000001c;1", "0?alldone,1:onemo,1") in new stack
    -- Goto (remindem,onemo,1)
    -- Executing [onemo@remindem:1] NoOp("Local/501@cos-all-0000001c;1", "Callee Replayed Message. Dialed: .") in new stack
    -- Executing [onemo@remindem:2] Playback("Local/501@cos-all-0000001c;1", "custom/") in new stack
[2023-07-31 15:55:03] WARNING[55528][C-00000034]: file.c:824 ast_openstream_full: File custom/ does not exist in any format
[2023-07-31 15:55:03] WARNING[55528][C-00000034]: file.c:1303 ast_streamfile: Unable to open custom/ (format (slin)): No such file or directory
[2023-07-31 15:55:03] WARNING[55528][C-00000034]: app_playback.c:512 playback_exec: Playback failed on Local/501@cos-all-0000001c;1 for custom/
    -- Executing [onemo@remindem:3] Set("Local/501@cos-all-0000001c;1", "counter=1+1+1") in new stack
    -- Executing [onemo@remindem:4] GotoIf("Local/501@cos-all-0000001c;1", "1?alldone,1:s,11") in new stack
    -- Goto (remindem,alldone,1)
    -- Executing [alldone@remindem:1] BackGround("Local/501@cos-all-0000001c;1", "goodbye") in new stack
    -- <Local/501@cos-all-0000001c;1> Playing 'goodbye.ulaw' (language 'en')
    -- Executing [alldone@remindem:2] NoOp("Local/501@cos-all-0000001c;1", "Callee Acknowledged Call. Dialed: .") in new stack
    -- Executing [alldone@remindem:3] Hangup("Local/501@cos-all-0000001c;1", "") in new stack
  == Spawn extension (remindem, alldone, 3) exited non-zero on 'Local/501@cos-all-0000001c;1'
    -- Executing [h@remindem:1] Set("Local/501@cos-all-0000001c;1", "email=") in new stack
    -- Executing [h@remindem:2] GotoIf("Local/501@cos-all-0000001c;1", "1?4") in new stack
    -- Goto (remindem,h,4)
    -- Executing [h@remindem:4] NoOp("Local/501@cos-all-0000001c;1", "Callee hung up. Dialed: .") in new stack
    -- Executing [h@remindem:5] System("Local/501@cos-all-0000001c;1", "/bin/rm -f /var/lib/asterisk/sounds/custom/...gsm") in new stack
    -- Executing [h@remindem:6] Hangup("Local/501@cos-all-0000001c;1", "") in new stack
  == Spawn extension (remindem, h, 6) exited non-zero on 'Local/501@cos-all-0000001c;1'
[2023-07-31 15:55:04] NOTICE[55528][C-00000034]: pbx_spool.c:463 attempt_thread: Call completed to local/501@cos-all
    -- User hung up

Can you write down in detail the requirements you need and the targets you want to achieve by using callfiles on ipbx vitalpbx?

This is an attempt to mimic a reminder functionality found in another PBX which is based on Asterisk. An extension is dialed to access the function and a reminder time is set via the touch tone pad. This produces a call file to be processed at the specified time. Depending on the context specified, the reminder call can be made to either an internal or external number.

1 Like

Here’s a quick summary of the application: Best of Nerd Vittles - Telephone Reminders 4.0

For the phone interface, a user dials 123 then records a reminder message and specifies a date and time for the reminder, whether it will be recurring, and to which extension or external number it will be delivered. Everything is working in the phone interface except specifying 501 as the callee for the reminder results in the call being sent to voicemail for 501 even though the 501 extension is registered and active with VitalPBX.