extensions_custom.conf doesn't appear to be working correctly

I have a home automation system setup to receive webhooks. I have a doorphone in front of my house that is configured to automatically call a call queue I setup 9999. The extension is question is 999. I’ve setup an extension_custom.conf file with the following informaiton

[from-internal-custom]
exten => 999,1,Answer()
same => n,System(echo "Call started" > /tmp/call_start.txt)
same => n,System(echo "Executing curl command" > /tmp/curl_test.txt)
same => n,System(curl -X POST http://<my-ipaddress>/api/webhook/doorbell)
same => n,Hangup()

Where I specified the IP to my home autiomation system. I’ve verified the webhook is working and I’ve verified that my freepbx server can trigger the webhook manually. However the code in my extensions_custom.conf file never seems to launch. I’ve checked the logfile there’s no mention of the file execution or anything, the call goes through like it’s supposed to, I’ve ran the asterisk cli and made the test call the call goes through but there’s nothing showing the code was executed at all. I’ve done a fer itterations of this file including trying to create a custom log file and output to it, but none of that gets executed either. It seems that FreePBX is ignoring my extensions_custom.conf file completely. I’ve tried editing the file both in the web app as well as manually it does not appear to matter. Please help.

You should actually provide the log so others can examine what is actually going on.

Here is my log from /var/log/asterisk/full

[2024-08-26 00:00:00] Asterisk 21.4.1 built by jenkins @ 2d4a220bf72f on a x86_64 running Linux on 2024-07-30 05:37:05 UTC
[2024-08-26 00:00:00] VERBOSE[77999] logger.c: Asterisk Queue Logger restarted
[2024-08-26 00:00:00] VERBOSE[77999] asterisk.c: Remote UNIX connection disconnected
[2024-08-26 19:33:44] VERBOSE[39229] netsock2.c: Using SIP RTP Audio TOS bits 184
[2024-08-26 19:33:44] VERBOSE[39229] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2024-08-26 19:33:44] VERBOSE[39229] netsock2.c: Using SIP RTP Audio CoS mark 5
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:1] Gosub("PJSIP/999-0000000e", "macro-user-callerid,s,1()") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/999-0000000e", "TOUCH_MONITOR=1724715224.28") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/999-0000000e", "CHANCONTEXT=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:3] Progress("PJSIP/999-0000000e", "") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/999-0000000e", "CHANCONTEXT=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/999-0000000e", "CHANEXTENCONTEXT=999-0000000e") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/999-0000000e", "CHANEXTEN=999-0000000e") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/999-0000000e", "CALLERID(number)=999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/999-0000000e", "AMPUSER=999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/999-0000000e", "HOTDESCKCHAN=999-0000000e") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/999-0000000e", "HOTDESKEXTEN=999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/999-0000000e", "HOTDESKCALL=0") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/999-0000000e", "0?Set(HOTDESKCALL=1)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/999-0000000e", "0?Set(CALLERID(name)=)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/999-0000000e", "0?report") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("PJSIP/999-0000000e", "1?Set(REALCALLERIDNUM=999)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:16] Set("PJSIP/999-0000000e", "AMPUSER=999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("PJSIP/999-0000000e", "0?limit") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:18] Set("PJSIP/999-0000000e", "AMPUSERCIDNAME=Front Door") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("PJSIP/999-0000000e", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("PJSIP/999-0000000e", "0?report") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:21] Set("PJSIP/999-0000000e", "AMPUSERCID=999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:22] Set("PJSIP/999-0000000e", "__DIAL_OPTIONS=HhTtr") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:23] Set("PJSIP/999-0000000e", "CALLERID(all)="Front Door" <999>") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:24] ExecIf("PJSIP/999-0000000e", "0?Set(CUSDIAL=)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:25] ExecIf("PJSIP/999-0000000e", "0?Set(CALLERID(all)="Front Door" <999>)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:26] GotoIf("PJSIP/999-0000000e", "0?limit") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:27] ExecIf("PJSIP/999-0000000e", "0?Set(GROUP(concurrency_limit)=999)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:28] ExecIf("PJSIP/999-0000000e", "0?Set(CHANNEL(language)=)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:29] NoOp("PJSIP/999-0000000e", "Macro depricated!! To keep the same line numbers") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:30] NoOp("PJSIP/999-0000000e", "Macro depricated !!  To keep the same line numbers") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("PJSIP/999-0000000e", "0?continue") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:32] ExecIf("PJSIP/999-0000000e", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:33] Set("PJSIP/999-0000000e", "__TTL=64") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:34] GotoIf("PJSIP/999-0000000e", "1?continue") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx_builtins.c: Goto (macro-user-callerid,s,50)
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/999-0000000e", "CALLERID(number)=999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:51] Set("PJSIP/999-0000000e", "CALLERID(name)=Front Door") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:52] GotoIf("PJSIP/999-0000000e", "0?cnum") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/999-0000000e", "__MCNUM=999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/999-0000000e", "__MCNAME=Front Door") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/999-0000000e", "__MCEXTEN=999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:56] Set("PJSIP/999-0000000e", "__MCORGCHAN=PJSIP/999-0000000e") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:57] Set("PJSIP/999-0000000e", "CDR(cnam)=Front Door") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:58] Set("PJSIP/999-0000000e", "CDR(cnum)=999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-user-callerid:59] Return("PJSIP/999-0000000e", "") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:2] Set("PJSIP/999-0000000e", "__MCQUEUE=9999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:3] Answer("PJSIP/999-0000000e", "") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:4] Set("PJSIP/999-0000000e", "__FROMQUEUEEXTEN=999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:5] Gosub("PJSIP/999-0000000e", "macro-blkvm-set,s,1(reset)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-blkvm-set:1] ExecIf("PJSIP/999-0000000e", "1?Set(__BLKVM_CHANNEL=PJSIP/999-0000000e)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-blkvm-set:2] Set("PJSIP/999-0000000e", "SHARED(BLKVM,PJSIP/999-0000000e)=TRUE") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-blkvm-set:3] Set("PJSIP/999-0000000e", "GOSUB_RETVAL=TRUE") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-blkvm-set:4] Return("PJSIP/999-0000000e", "") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:6] ExecIf("PJSIP/999-0000000e", "1?Set(_DIAL_OPTIONS=HhTtrU(macro-auto-blkvm))") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:7] Set("PJSIP/999-0000000e", "__NODEST=9999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:8] Set("PJSIP/999-0000000e", "__QCONTEXT=0") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:9] Set("PJSIP/999-0000000e", "QCIDPP=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:10] Set("PJSIP/999-0000000e", "VQ_CIDPP=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:11] ExecIf("PJSIP/999-0000000e", "0?Gosub(macro-prepend-cid,s,1())") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:12] Set("PJSIP/999-0000000e", "QAINFO=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:13] Set("PJSIP/999-0000000e", "VQ_AINFO=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:14] Set("PJSIP/999-0000000e", "__RVOL_MODE=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:15] ExecIf("PJSIP/999-0000000e", "0?Set(__ALERT_INFO=)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:16] Set("PJSIP/999-0000000e", "QJOINMSG=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:17] Set("PJSIP/999-0000000e", "VQ_JOINMSG=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:18] Set("PJSIP/999-0000000e", "QCANCELMISSED=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:19] Set("PJSIP/999-0000000e", "QRETRY=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:20] Set("PJSIP/999-0000000e", "VQ_RETRY=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:21] Set("PJSIP/999-0000000e", "QOPTIONS=t") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:22] Set("PJSIP/999-0000000e", "VQ_OPTIONS=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:23] Set("PJSIP/999-0000000e", "QGOSUB=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:24] Set("PJSIP/999-0000000e", "VQ_GOSUB=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:25] Set("PJSIP/999-0000000e", "QAGI=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:26] Set("PJSIP/999-0000000e", "VQ_AGI=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:27] Set("PJSIP/999-0000000e", "QRULE=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:28] Set("PJSIP/999-0000000e", "VQ_RULE=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:29] Set("PJSIP/999-0000000e", "QPOSITION=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:30] Set("PJSIP/999-0000000e", "VQ_POSITION=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:31] Gosub("PJSIP/999-0000000e", "sub-record-check,s,1(q,9999,dontcare)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/999-0000000e", "0?initialized") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:2] Set("PJSIP/999-0000000e", "__REC_STATUS=INITIALIZED") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:3] Set("PJSIP/999-0000000e", "NOW=1724715224") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:4] Set("PJSIP/999-0000000e", "__DAY=26") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:5] Set("PJSIP/999-0000000e", "__MONTH=08") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:6] Set("PJSIP/999-0000000e", "__YEAR=2024") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:7] Set("PJSIP/999-0000000e", "__TIMESTR=20240826-193344") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:8] Set("PJSIP/999-0000000e", "__FROMEXTEN=999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:9] Set("PJSIP/999-0000000e", "__MON_FMT=wav") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/999-0000000e", "Recordings initialized") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/999-0000000e", "0?Set(ARG3=dontcare)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/999-0000000e", "REC_POLICY_MODE_SAVE=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/999-0000000e", "0?Set(REC_STATUS=NO)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/999-0000000e", "1?checkaction") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/999-0000000e", "0?sub-record-check,q,1") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:18] NoOp("PJSIP/999-0000000e", "Generic q Recording Check - 999 9999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:19] Gosub("PJSIP/999-0000000e", "recordcheck,1(dontcare,q,9999)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/999-0000000e", "Starting recording check against dontcare") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/999-0000000e", "dontcare") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/999-0000000e", "") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@sub-record-check:20] Return("PJSIP/999-0000000e", "") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:32] Set("PJSIP/999-0000000e", "__SIGNORE=TRUE") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:33] Set("PJSIP/999-0000000e", "__QC_CONFIRM=0") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:34] GotoIf("PJSIP/999-0000000e", "0?QVQANNOUNCE:NOQVQANNOUNCE") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx_builtins.c: Goto (from-internal,9999,39)
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:39] Set("PJSIP/999-0000000e", "VQ_CONFIRMMSG=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:40] ExecIf("PJSIP/999-0000000e", "0?Playback(, )") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:41] QueueLog("PJSIP/999-0000000e", "9999,1724715224.28,NONE,DID,") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:42] Set("PJSIP/999-0000000e", "QAANNOUNCE=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:43] Set("PJSIP/999-0000000e", "VQ_AANNOUNCE=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:44] Set("PJSIP/999-0000000e", "QMOH=default") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:45] Set("PJSIP/999-0000000e", "VQ_MOH=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:46] ExecIf("PJSIP/999-0000000e", "1?Set(__MOHCLASS=default)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:47] ExecIf("PJSIP/999-0000000e", "1?Set(CHANNEL(musicclass)=default)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:48] Set("PJSIP/999-0000000e", "QMAXWAIT=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:49] Set("PJSIP/999-0000000e", "VQ_MAXWAIT=") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:50] Set("PJSIP/999-0000000e", "QUEUENUM=9999") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:51] Set("PJSIP/999-0000000e", "QUEUEJOINTIME=1724715224") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:52] Set("PJSIP/999-0000000e", "CHANNEL(hangup_handler_push)=queueCall-hangup,s,1(9999,1724715224.28,PJSIP/999-0000000e)") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] pbx.c: Executing [9999@from-internal:53] Queue("PJSIP/999-0000000e", "9999,t,,,,,,,,") in new stack
[2024-08-26 19:33:44] VERBOSE[249756][C-0000000e] res_musiconhold.c: Started music on hold, class 'default', on channel 'PJSIP/999-0000000e'
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] res_musiconhold.c: Stopped music on hold on PJSIP/999-0000000e
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Spawn extension (from-internal, 9999, 53) exited non-zero on 'PJSIP/999-0000000e'
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [h@from-internal:1] Gosub("PJSIP/999-0000000e", "macro-hangupcall,s,1") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-hangupcall:1] Set("PJSIP/999-0000000e", "__MCVMSTATUS=") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-hangupcall:2] Gosub("PJSIP/999-0000000e", "app-missedcall-hangup,s,1()") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp("PJSIP/999-0000000e", "Dialed: s") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp("PJSIP/999-0000000e", "Caller: 999") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@app-missedcall-hangup:3] GotoIf("PJSIP/999-0000000e", "0?exit") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@app-missedcall-hangup:4] Set("PJSIP/999-0000000e", "EXTENNUM=s") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@app-missedcall-hangup:5] Set("PJSIP/999-0000000e", "FEXTENNUM=s") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@app-missedcall-hangup:6] GotoIf("PJSIP/999-0000000e", "0?exit") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@app-missedcall-hangup:7] AGI("PJSIP/999-0000000e", "agi://127.0.0.1/missedcallnotify.php,s,,s,0,,PJSIP/999-0000000e,,9999,,") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] res_agi.c: <PJSIP/999-0000000e>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@app-missedcall-hangup:8] Return("PJSIP/999-0000000e", "") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-hangupcall:3] UserEvent("PJSIP/999-0000000e", "MES,RTPAUDIOQOSMESBRIDGED:,RTPAUDIOQOSMES:   minrxmes=088.087887;   maxrxmes=088.087887;   avgrxmes=088.087887;   stdevrxmes=000.000113;   mintxmes=088.087887;   maxtxmes=088.087887;   avgtxmes=088.087887;   stdevtxmes=000.000000;") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-hangupcall:4] GotoIf("PJSIP/999-0000000e", "1?theend") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx_builtins.c: Goto (macro-hangupcall,s,6)
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-hangupcall:6] ExecIf("PJSIP/999-0000000e", "0?Set(CDR(recordingfile)=)") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@macro-hangupcall:7] Hangup("PJSIP/999-0000000e", "") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/999-0000000e'
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] app_stack.c: PJSIP/999-0000000e Internal Gosub(queueCall-hangup,s,1(9999,1724715224.28,PJSIP/999-0000000e)) start
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@queueCall-hangup:1] NoOp("PJSIP/999-0000000e", "9999") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@queueCall-hangup:2] UserEvent("PJSIP/999-0000000e", "QueueCallHangup,Q:9999,UNIQ:1724715224.28,CHAN:PJSIP/999-0000000e,SCD_CONNECT_AGENT:") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] pbx.c: Executing [s@queueCall-hangup:3] Return("PJSIP/999-0000000e", "") in new stack
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] app_stack.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'PJSIP/999-0000000e'
[2024-08-26 19:33:51] VERBOSE[249756][C-0000000e] app_stack.c: PJSIP/999-0000000e Internal Gosub(queueCall-hangup,s,1(9999,1724715224.28,PJSIP/999-0000000e)) complete GOSUB_RETVAL=

Dialplan is for 999 but you dialed 9999.

The extension I’m dialing from is 999

That’s irrelevant unless you configure caller ID dependent routes.

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