Missed Call Notification module does not send out emails

FreePBX Applications / Modules
1

Hello,

I upgraded vom FreePBX 16 to 17 yesterday and restored a recent backup.

PBX Version: 17.0.19.24
PBX Distro: 12.7.8-2408-1.sng12
Asterisk Version: 22.2.0

The last missed call notifications came in yesterday, but no longer since the upgrade. The version of Missed Call Notification module is 17.0.3 (I also tried 17.0.2, because I noticed this topic).

When I check the logs I do not see any indication that a mail is sent out. Also syslog and mail.log are clear of any mails. But mailing nevertheless works, voicemail message are sent, also fail2ban information and so on. It is a problem with FreePBX/the module.

What kind of information do you need? I’m kinda newbie here. :wink:

Regards,
Roi

2

Go to the cli as root and type this:

fwconsole ma downloadinstall missedcall --tag=17.0.2 && fwconsole chown && fwconsole r

3

Thank you, but that is, what I already did. I noticed the other topic and exactly did this. The version 17.0.2 is also still running here and since then there were several reloads/restarts.

4

Can you open a support case in help.sangoma.com and share the case number ? I will take the case and work with you.

5

Can we see some debugging from this call notice not working?

6

You mean that?

Part 1:

Missed Call Notification module does not send out emails.txt0000644000175000017500000014257114777243106022376 0ustar  roiroi3887	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("PJSIP/1und1_Number1-000000b6", "0?blacklisted") in new stack
3888	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@app-blacklist-check:2] Set("PJSIP/1und1_Number1-000000b6", "CALLED_BLACKLIST=1") in new stack
3889	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@app-blacklist-check:3] Return("PJSIP/1und1_Number1-000000b6", "") in new stack
3890	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:7] Set("PJSIP/1und1_Number1-000000b6", "CDR(did)=4971123456789") in new stack
3891	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:8] GotoIf("PJSIP/1und1_Number1-000000b6", "0?") in new stack
3892	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:9] ExecIf("PJSIP/1und1_Number1-000000b6", "0 ?Set(CALLERID(name)=+491601234567)") in new stack
3893	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:10] Set("PJSIP/1und1_Number1-000000b6", "__MOHCLASS=") in new stack
3894	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:11] Set("PJSIP/1und1_Number1-000000b6", "__REVERSAL_REJECT=FALSE") in new stack
3895	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:12] GotoIf("PJSIP/1und1_Number1-000000b6", "1?post-reverse-charge") in new stack
3896	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (from-pstn,4971123456789,14)
3897	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:14] NoOp("PJSIP/1und1_Number1-000000b6", "") in new stack
3898	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:15] Set("PJSIP/1und1_Number1-000000b6", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
3899	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:16] Set("PJSIP/1und1_Number1-000000b6", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
3900	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:17] Set("PJSIP/1und1_Number1-000000b6", "CALLERID(name-pres)=allowed_not_screened") in new stack
3901	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:18] Set("PJSIP/1und1_Number1-000000b6", "CALLERID(num-pres)=allowed_not_screened") in new stack
3902	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:19] Set("PJSIP/1und1_Number1-000000b6", "CIDSFSCHEME=QUxMfEFMTA==") in new stack
3903	[2025-04-14 19:23:31] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:20] AGI("PJSIP/1und1_Number1-000000b6", "agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi") in new stack
3904	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Executing Scheme..
3905	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Scheme Asked is: ALL
3906	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: The DID is: 4971123456789
3907	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: The CNUM is: +491601234567
3908	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: The CNAME is: +491601234567
3909	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi:
3910	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Starting scheme carddavmiddleware
3911	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: No callerid found
3912	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi:
3913	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Starting scheme Default
3914	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before strip_tags: Caller Name, length: 13, mb_strlen: 13
3915	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after strip_tags: Caller Name, length: 13, mb_strlen: 13
3916	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Strip_Accent_Characters: 1
3917	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before stripAccents: Caller Name, length: 13, mb_strlen: 13
3918	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after stripAccents: Caller Name, length: 13, mb_strlen: 13
3919	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before preg_replace: Caller Name, length: 13, mb_strlen: 13
3920	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after preg_replace: Caller Name, length: 13, mb_strlen: 13
3921	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Character Encodings: 'ISO-8859-1'
3922	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Converting result to UTF-8
3923	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before mb_convert_encoding: Caller Name, length: 13, mb_strlen: 13
3924	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after mb_convert_encoding: Caller Name, length: 13, mb_strlen: 13
3925	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller_Id_Max_Length: '60'
3926	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before mb_substr: Caller Name, length: 13, mb_strlen: 13
3927	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after mb_substr: Caller Name, length: 13, mb_strlen: 13
3928	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID before mb_substr: Caller Name, length: 13, mb_strlen: 13
3929	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Caller ID after mb_substr: Caller Name, length: 13, mb_strlen: 13
3930	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Setting caller id to: Caller Name
3931	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Setting channel variable SUPERFECTA_NEW: Caller Name
3932	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Setting channel variable SUPERFECTA_OLD: +491601234567
3933	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: CID Determined to be: 'Caller Name'
3934	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi: Attempting to set lookupcid
3935	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: <PJSIP/1und1_Number1-000000b6>AGI Script agi://127.0.0.1//var/www/html/admin/modules/superfecta/agi/superfecta.agi completed, returning 0
3936	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:21] Set("PJSIP/1und1_Number1-000000b6", "CALLERID(name)=Caller Name") in new stack
3937	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:22] NoOp("PJSIP/1und1_Number1-000000b6", "CallerID Entry Point") in new stack
3938	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:23] Set("PJSIP/1und1_Number1-000000b6", "__CRM_DIRECTION=INBOUND") in new stack
3939	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:24] Set("PJSIP/1und1_Number1-000000b6", "__CRM_SOURCE=+491601234567") in new stack
3940	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:25] Set("PJSIP/1und1_Number1-000000b6", "__CRM_LINKEDID=1744651411.343") in new stack
3941	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:26] AGI("PJSIP/1und1_Number1-000000b6", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
3942	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: <PJSIP/1und1_Number1-000000b6>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
3943	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:27] ExecIf("PJSIP/1und1_Number1-000000b6", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
3944	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [4971123456789@from-pstn:28] Goto("PJSIP/1und1_Number1-000000b6", "ext-group,621,1") in new stack
3945	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (ext-group,621,1)
3946	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:1] GotoIf("PJSIP/1und1_Number1-000000b6", "0?cid") in new stack
3947	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:2] Set("PJSIP/1und1_Number1-000000b6", "__MCGROUP=621") in new stack
3948	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:3] PlayTones("PJSIP/1und1_Number1-000000b6", "ring") in new stack
3949	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:4] Progress("PJSIP/1und1_Number1-000000b6", "") in new stack
3950	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:5] Gosub("PJSIP/1und1_Number1-000000b6", "macro-user-callerid,s,1()") in new stack
3951	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/1und1_Number1-000000b6", "TOUCH_MONITOR=1744651411.343") in new stack
3952	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/1und1_Number1-000000b6", "CHANCONTEXT=") in new stack
3953	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:3] Set("PJSIP/1und1_Number1-000000b6", "CHANCONTEXT=") in new stack
3954	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/1und1_Number1-000000b6", "CHANEXTENCONTEXT=1und1_Number1-000000b6") in new stack
3955	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/1und1_Number1-000000b6", "CHANEXTEN=1und1_Number1-000000b6") in new stack
3956	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/1und1_Number1-000000b6", "CALLERID(number)=+491601234567") in new stack
3957	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/1und1_Number1-000000b6", "AMPUSER=+491601234567") in new stack
3958	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/1und1_Number1-000000b6", "HOTDESCKCHAN=1und1_Number1-000000b6") in new stack
3959	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/1und1_Number1-000000b6", "HOTDESKEXTEN=1und1_Number1") in new stack
3960	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/1und1_Number1-000000b6", "HOTDESKCALL=0") in new stack
3961	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:11] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(HOTDESKCALL=1)") in new stack
3962	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(CALLERID(name)=)") in new stack
3963	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:13] GotoIf("PJSIP/1und1_Number1-000000b6", "0?report") in new stack
3964	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("PJSIP/1und1_Number1-000000b6", "1?Set(REALCALLERIDNUM=+491601234567)") in new stack
3965	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:15] Set("PJSIP/1und1_Number1-000000b6", "AMPUSER=") in new stack
3966	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:16] GotoIf("PJSIP/1und1_Number1-000000b6", "0?limit") in new stack
3967	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:17] Set("PJSIP/1und1_Number1-000000b6", "AMPUSERCIDNAME=") in new stack
3968	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:18] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
3969	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:19] GotoIf("PJSIP/1und1_Number1-000000b6", "1?report") in new stack
3970	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (macro-user-callerid,s,28)
3971	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:28] NoOp("PJSIP/1und1_Number1-000000b6", "Macro depricated!! To keep the same line numbers") in new stack
3972	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:29] NoOp("PJSIP/1und1_Number1-000000b6", "Macro depricated !! To keep the same line numbers") in new stack
3973	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("PJSIP/1und1_Number1-000000b6", "0?continue") in new stack
3974	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:31] ExecIf("PJSIP/1und1_Number1-000000b6", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
3975	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:32] Set("PJSIP/1und1_Number1-000000b6", "__TTL=64") in new stack
3976	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:33] GotoIf("PJSIP/1und1_Number1-000000b6", "1?continue") in new stack
3977	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (macro-user-callerid,s,49)
3978	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:49] Set("PJSIP/1und1_Number1-000000b6", "CALLERID(number)=+491601234567") in new stack
3979	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/1und1_Number1-000000b6", "CALLERID(name)=Caller Name") in new stack
3980	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:51] GotoIf("PJSIP/1und1_Number1-000000b6", "0?cnum") in new stack
3981	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:52] Set("PJSIP/1und1_Number1-000000b6", "__MCNUM=+491601234567") in new stack
3982	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/1und1_Number1-000000b6", "__MCNAME=Caller Name") in new stack
3983	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/1und1_Number1-000000b6", "__MCEXTEN=") in new stack
3984	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/1und1_Number1-000000b6", "__MCORGCHAN=PJSIP/1und1_Number1-000000b6") in new stack
3985	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:56] Set("PJSIP/1und1_Number1-000000b6", "CDR(cnam)=Caller Name") in new stack
3986	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:57] Set("PJSIP/1und1_Number1-000000b6", "CDR(cnum)=+491601234567") in new stack
3987	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:58] Return("PJSIP/1und1_Number1-000000b6", "") in new stack
3988	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:6] Gosub("PJSIP/1und1_Number1-000000b6", "macro-blkvm-setifempty,s,1()") in new stack
3989	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("PJSIP/1und1_Number1-000000b6", "1?init") in new stack
3990	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
3991	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set("PJSIP/1und1_Number1-000000b6", "__BLKVM_CHANNEL=PJSIP/1und1_Number1-000000b6") in new stack
3992	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set("PJSIP/1und1_Number1-000000b6", "SHARED(BLKVM,PJSIP/1und1_Number1-000000b6)=TRUE") in new stack
3993	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set("PJSIP/1und1_Number1-000000b6", "GOSUB_RETVAL=TRUE") in new stack
3994	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-blkvm-setifempty:7] Return("PJSIP/1und1_Number1-000000b6", "TRUE") in new stack
3995	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:7] GotoIf("PJSIP/1und1_Number1-000000b6", "1?skipov") in new stack
3996	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (ext-group,621,10)
3997	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:10] Set("PJSIP/1und1_Number1-000000b6", "RRNODEST=") in new stack
3998	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:11] Set("PJSIP/1und1_Number1-000000b6", "__NODEST=621") in new stack
3999	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:12] GosubIf("PJSIP/1und1_Number1-000000b6", "0?sub-rgsetcid,s,1()") in new stack
4000	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:13] Gosub("PJSIP/1und1_Number1-000000b6", "sub-record-check,s,1(rg,621,dontcare)") in new stack
4001	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/1und1_Number1-000000b6", "13?initialized") in new stack
4002	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (sub-record-check,s,10)
4003	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/1und1_Number1-000000b6", "Recordings initialized") in new stack
4004	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(ARG3=dontcare)") in new stack
4005	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/1und1_Number1-000000b6", "REC_POLICY_MODE_SAVE=") in new stack
4006	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(REC_STATUS=NO)") in new stack
4007	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/1und1_Number1-000000b6", "2?checkaction") in new stack
4008	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (sub-record-check,s,17)
4009	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/1und1_Number1-000000b6", "0?sub-record-check,rg,1") in new stack
4010	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:18] NoOp("PJSIP/1und1_Number1-000000b6", "Generic rg Recording Check - +491601234567 621") in new stack
4011	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:19] Gosub("PJSIP/1und1_Number1-000000b6", "recordcheck,1(dontcare,rg,621)") in new stack
4012	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/1und1_Number1-000000b6", "Starting recording check against dontcare") in new stack
4013	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/1und1_Number1-000000b6", "dontcare") in new stack
4014	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
4015	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/1und1_Number1-000000b6", "") in new stack
4016	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:20] Return("PJSIP/1und1_Number1-000000b6", "") in new stack
4017	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:14] Set("PJSIP/1und1_Number1-000000b6", "RingGroupMethod=ringall") in new stack
4018	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:15] Set("PJSIP/1und1_Number1-000000b6", "__ALT_CONFIRM_MSG=") in new stack
4019	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-user-callerid:58] Return("PJSIP/1und1_Number1-000000b6", "") in new stack
4020	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:6] Gosub("PJSIP/1und1_Number1-000000b6", "macro-blkvm-setifempty,s,1()") in new stack
4021	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf("PJSIP/1und1_Number1-000000b6", "1?init") in new stack
4022	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
4023	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set("PJSIP/1und1_Number1-000000b6", "__BLKVM_CHANNEL=PJSIP/1und1_Number1-000000b6") in new stack
4024	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set("PJSIP/1und1_Number1-000000b6", "SHARED(BLKVM,PJSIP/1und1_Number1-000000b6)=TRUE") in new stack
4025	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set("PJSIP/1und1_Number1-000000b6", "GOSUB_RETVAL=TRUE") in new stack
4026	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-blkvm-setifempty:7] Return("PJSIP/1und1_Number1-000000b6", "TRUE") in new stack
4027	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:7] GotoIf("PJSIP/1und1_Number1-000000b6", "1?skipov") in new stack
4028	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (ext-group,621,10)
4029	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:10] Set("PJSIP/1und1_Number1-000000b6", "RRNODEST=") in new stack
4030	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:11] Set("PJSIP/1und1_Number1-000000b6", "__NODEST=621") in new stack
4031	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:12] GosubIf("PJSIP/1und1_Number1-000000b6", "0?sub-rgsetcid,s,1()") in new stack
4032	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:13] Gosub("PJSIP/1und1_Number1-000000b6", "sub-record-check,s,1(rg,621,dontcare)") in new stack
4033	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:1] GotoIf("PJSIP/1und1_Number1-000000b6", "13?initialized") in new stack
4034	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (sub-record-check,s,10)
4035	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:10] NoOp("PJSIP/1und1_Number1-000000b6", "Recordings initialized") in new stack
4036	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:11] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(ARG3=dontcare)") in new stack
4037	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:12] Set("PJSIP/1und1_Number1-000000b6", "REC_POLICY_MODE_SAVE=") in new stack
4038	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:13] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(REC_STATUS=NO)") in new stack
4039	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:14] GotoIf("PJSIP/1und1_Number1-000000b6", "2?checkaction") in new stack
4040	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (sub-record-check,s,17)
4041	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:17] GotoIf("PJSIP/1und1_Number1-000000b6", "0?sub-record-check,rg,1") in new stack
4042	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:18] NoOp("PJSIP/1und1_Number1-000000b6", "Generic rg Recording Check - +491601234567 621") in new stack
4043	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:19] Gosub("PJSIP/1und1_Number1-000000b6", "recordcheck,1(dontcare,rg,621)") in new stack
4044	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("PJSIP/1und1_Number1-000000b6", "Starting recording check against dontcare") in new stack
4045	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("PJSIP/1und1_Number1-000000b6", "dontcare") in new stack
4046	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
4047	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [recordcheck@sub-record-check:3] Return("PJSIP/1und1_Number1-000000b6", "") in new stack
4048	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@sub-record-check:20] Return("PJSIP/1und1_Number1-000000b6", "") in new stack
4049	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:14] Set("PJSIP/1und1_Number1-000000b6", "RingGroupMethod=ringall") in new stack
4050	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:15] Set("PJSIP/1und1_Number1-000000b6", "__ALT_CONFIRM_MSG=") in new stack
4051	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:16] GotoIf("PJSIP/1und1_Number1-000000b6", "0?RGVQANNOUNCE:NORGVQANNOUNCE") in new stack
4052	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (ext-group,621,19)
4053	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@ext-group:19] Gosub("PJSIP/1und1_Number1-000000b6", "macro-dial,s,1(25,HhTtrQ(NO_ANSWER),611-640-641-642)") in new stack
4054	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:1] NoOp("PJSIP/1und1_Number1-000000b6", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
4055	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:2] Set("PJSIP/1und1_Number1-000000b6", "__CRM_SOURCE=+491601234567") in new stack
4056	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:3] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(ALERT_INFO=)") in new stack
4057	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:4] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(ALERT_INFO=)") in new stack
4058	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:5] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(ALERT_INFO=)") in new stack
4059	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:6] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(CHANNEL(musicclass)=)") in new stack
7

Part 2:

4060	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:7] AGI("PJSIP/1und1_Number1-000000b6", "agi://127.0.0.1/dialparties.agi") in new stack
4061	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Starting New Dialparties.agi
4062	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Caller ID name is 'Caller Name' number is '+491601234567'
4063	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: CW Ignore is:
4064	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: CF Ignore is:
4065	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: CW IN_USE/BUSY is: 1
4066	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Ringgroup confirm is :
4067	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Methodology of ring is 'ringall'
4068	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 611 to extension map
4069	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 640 to extension map
4070	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 641 to extension map
4071	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 642 to extension map
4072	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 611 cf is disabled
4073	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 640 cf is disabled
4074	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 641 cf is disabled
4075	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 642 cf is disabled
4076	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 611 do not disturb is disabled
4077	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 640 do not disturb is disabled
4078	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 641 do not disturb is disabled
4079	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 642 do not disturb is disabled
4080	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/611
4081	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Ended up with real PJSIP Dial string PJSIP/611/sip:[email protected]:5060
4082	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/611 to +491601234567
4083	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/640
4084	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Ended up with no PJSIP contacts
4085	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/640 to +491601234567
4086	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/641
4087	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Ended up with no PJSIP contacts
4088	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/641 to +491601234567
4089	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Discovered PJSIP Endpoint PJSIP/642
4090	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Ended up with no PJSIP contacts
4091	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/642 to +491601234567
4092	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: Filtered ARG3: 611-640-641-642
4093	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOL_MODE ''
4094	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOL is:
4095	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: agi://127.0.0.1/dialparties.agi: RVOLPARENT is:
4096	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] res_agi.c: <PJSIP/1und1_Number1-000000b6>AGI Script agi://127.0.0.1/dialparties.agi completed, returning 0
4097	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:8] GotoIf("PJSIP/1und1_Number1-000000b6", "1?normdial") in new stack
4098	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (macro-dial,s,11)
4099	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:11] NoOp("PJSIP/1und1_Number1-000000b6", "Returned from dialparties with groups to dial") in new stack
4100	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:12] NoOp("PJSIP/1und1_Number1-000000b6", "ringall array ") in new stack
4101	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:13] NoOp("PJSIP/1und1_Number1-000000b6", "ds= PJSIP/611/sip:[email protected]:5060,25,HhtrQ(NO_ANSWER)U(macro-auto-blkvm) ") in new stack
4102	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:14] NoOp("PJSIP/1und1_Number1-000000b6", "dsextra= ") in new stack
4103	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:15] Set("PJSIP/1und1_Number1-000000b6", "ds=PJSIP/611/sip:[email protected]:5060,25,HhtrQ(NO_ANSWER)U(macro-auto-blkvm)") in new stack
4104	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:16] NoOp("PJSIP/1und1_Number1-000000b6", "ds= PJSIP/611/sip:[email protected]:5060,25,HhtrQ(NO_ANSWER)U(macro-auto-blkvm)") in new stack
4105	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:17] Set("PJSIP/1und1_Number1-000000b6", "__FMGL_DIAL=") in new stack
4106	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:18] Set("PJSIP/1und1_Number1-000000b6", "LOOPCNT=4") in new stack
4107	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:19] Set("PJSIP/1und1_Number1-000000b6", "ITER=1") in new stack
4108	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:20] Set("PJSIP/1und1_Number1-000000b6", "__EXTTOCALL=611") in new stack
4109	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:21] Set("PJSIP/1und1_Number1-000000b6", "__MCEXTTOCALL=611") in new stack
4110	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:22] NoOp("PJSIP/1und1_Number1-000000b6", "Working with 611") in new stack
4111	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:23] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
4112	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:24] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
4113	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:25] Set("PJSIP/1und1_Number1-000000b6", "ITER=2") in new stack
4114	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:26] GotoIf("PJSIP/1und1_Number1-000000b6", "1?ndloopbegin") in new stack
4115	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (macro-dial,s,20)
4116	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:20] Set("PJSIP/1und1_Number1-000000b6", "__EXTTOCALL=640") in new stack
4117	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:21] Set("PJSIP/1und1_Number1-000000b6", "__MCEXTTOCALL=640") in new stack
4118	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:22] NoOp("PJSIP/1und1_Number1-000000b6", "Working with 640") in new stack
4119	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:23] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
4120	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:24] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
4121	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:25] Set("PJSIP/1und1_Number1-000000b6", "ITER=3") in new stack
4122	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:26] GotoIf("PJSIP/1und1_Number1-000000b6", "1?ndloopbegin") in new stack
4123	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (macro-dial,s,20)
4124	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:20] Set("PJSIP/1und1_Number1-000000b6", "__EXTTOCALL=641") in new stack
4125	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:21] Set("PJSIP/1und1_Number1-000000b6", "__MCEXTTOCALL=641") in new stack
4126	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:22] NoOp("PJSIP/1und1_Number1-000000b6", "Working with 641") in new stack
4127	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:23] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
4128	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:24] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
4129	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:25] Set("PJSIP/1und1_Number1-000000b6", "ITER=4") in new stack
4130	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:26] GotoIf("PJSIP/1und1_Number1-000000b6", "1?ndloopbegin") in new stack
4131	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (macro-dial,s,20)
4132	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:20] Set("PJSIP/1und1_Number1-000000b6", "__EXTTOCALL=642") in new stack
4133	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:21] Set("PJSIP/1und1_Number1-000000b6", "__MCEXTTOCALL=642") in new stack
4134	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:22] NoOp("PJSIP/1und1_Number1-000000b6", "Working with 642") in new stack
4135	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:23] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
4136	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:24] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
4137	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:25] Set("PJSIP/1und1_Number1-000000b6", "ITER=5") in new stack
4138	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:26] GotoIf("PJSIP/1und1_Number1-000000b6", "0?ndloopbegin") in new stack
4139	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:27] Gosub("PJSIP/1und1_Number1-000000b6", "macro-dial-ringall-predial-hook,s,1()") in new stack
4140	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] Return("PJSIP/1und1_Number1-000000b6", "") in new stack
4141	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:28] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
4142	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:29] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(RGFMDIAL=642):Set(RGFMDIAL=621)") in new stack
4143	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:30] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(ds=PJSIP/611/sip:[email protected]:5060,25,HhtrQ(NO_ANSWER)U(macro-auto-blkvm)g)") in new stack
4144	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@macro-dial:31] Gosub("PJSIP/1und1_Number1-000000b6", "dial-with-exten,621,1()") in new stack
4145	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [621@dial-with-exten:1] Dial("PJSIP/1und1_Number1-000000b6", "PJSIP/611/sip:[email protected]:5060,25,HhtrQ(NO_ANSWER)U(macro-auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
4146	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] app_stack.c: PJSIP/611-000000b7 Internal Gosub(func-apply-sipheaders,s,1) start
4147	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("PJSIP/611-000000b7", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
4148	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("PJSIP/611-000000b7", "Applying SIP Headers to channel PJSIP/611-000000b7") in new stack
4149	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@func-apply-sipheaders:3] Set("PJSIP/611-000000b7", "localchan=611-000000b7") in new stack
4150	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@func-apply-sipheaders:4] Set("PJSIP/611-000000b7", "DialMCEXT=611") in new stack
4151	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@func-apply-sipheaders:5] Set("PJSIP/611-000000b7", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,611,1") in new stack
4152	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@func-apply-sipheaders:6] Set("PJSIP/611-000000b7", "Dchan=611-000000b7") in new stack
4153	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@func-apply-sipheaders:7] Set("PJSIP/611-000000b7", "TECH=PJSIP") in new stack
4154	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@func-apply-sipheaders:8] Set("PJSIP/611-000000b7", "SIPHEADERKEYS=") in new stack
4155	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@func-apply-sipheaders:9] While("PJSIP/611-000000b7", "0") in new stack
4156	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] app_while.c: Jumping to priority 15
4157	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@func-apply-sipheaders:16] Return("PJSIP/611-000000b7", "") in new stack
4158	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] app_stack.c: Spawn extension (from-internal, 621, 1) exited non-zero on 'PJSIP/611-000000b7'
4159	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] app_stack.c: PJSIP/611-000000b7 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
4160	[2025-04-14 19:23:32] VERBOSE[21747] netsock2.c: Using SIP RTP Audio TOS bits 184
4161	[2025-04-14 19:23:32] VERBOSE[21747] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
4162	[2025-04-14 19:23:32] VERBOSE[21747] netsock2.c: Using SIP RTP Audio CoS mark 5
4163	[2025-04-14 19:23:32] VERBOSE[411699][C-000000a1] app_dial.c: Called PJSIP/611/sip:[email protected]:5060
4164	[2025-04-14 19:23:34] VERBOSE[411699][C-000000a1] app_dial.c: PJSIP/611-000000b7 is ringing
4165	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] app_stack.c: PJSIP/611-000000b7 Internal Gosub(app-missedcall-hangup,611,1) start
4166	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [611@app-missedcall-hangup:1] NoOp("PJSIP/611-000000b7", "Dialed: 611") in new stack
4167	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [611@app-missedcall-hangup:2] NoOp("PJSIP/611-000000b7", "Caller: ") in new stack
4168	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [611@app-missedcall-hangup:3] GotoIf("PJSIP/611-000000b7", "0?exit") in new stack
4169	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [611@app-missedcall-hangup:4] Set("PJSIP/611-000000b7", "EXTENNUM=611") in new stack
4170	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [611@app-missedcall-hangup:5] Set("PJSIP/611-000000b7", "FEXTENNUM=611") in new stack
4171	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [611@app-missedcall-hangup:6] GotoIf("PJSIP/611-000000b7", "0?exit") in new stack
4172	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [611@app-missedcall-hangup:7] AGI("PJSIP/611-000000b7", "agi://127.0.0.1/missedcallnotify.php,611,,611,1,enable,PJSIP/611-000000b7,,,621,") in new stack
4173	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] res_agi.c: <PJSIP/611-000000b7>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
4174	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [611@app-missedcall-hangup:8] Return("PJSIP/611-000000b7", "") in new stack
4175	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] app_stack.c: Spawn extension (from-internal, 621, 1) exited non-zero on 'PJSIP/611-000000b7'
4176	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] app_stack.c: PJSIP/611-000000b7 Internal Gosub(app-missedcall-hangup,611,1) complete GOSUB_RETVAL=
4177	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] app_stack.c: PJSIP/611-000000b7 Internal Gosub(crm-hangup,s,1) start
4178	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/611-000000b7", "Sending Hangup to CRM") in new stack
4179	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/611-000000b7", "HANGUP CAUSE: 127") in new stack
4180	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/611-000000b7", "0?Set(__CRM_VOICEMAIL=)") in new stack
4181	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/611-000000b7", "MASTER CHANNEL: 1744651412.344 = 1744651411.343") in new stack
4182	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/611-000000b7", "1?return") in new stack
4183	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx_builtins.c: Goto (crm-hangup,s,8)
4184	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/611-000000b7", "") in new stack
4185	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] app_stack.c: Spawn extension (from-internal, 621, 1) exited non-zero on 'PJSIP/611-000000b7'
4186	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] app_stack.c: PJSIP/611-000000b7 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
4187	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Spawn extension (dial-with-exten, 621, 1) exited non-zero on 'PJSIP/1und1_Number1-000000b6'
4188	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] app_stack.c: PJSIP/1und1_Number1-000000b6 Internal Gosub(crm-hangup,s,1) start
4189	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/1und1_Number1-000000b6", "Sending Hangup to CRM") in new stack
4190	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/1und1_Number1-000000b6", "HANGUP CAUSE: 127") in new stack
4191	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/1und1_Number1-000000b6", "0?Set(__CRM_VOICEMAIL=)") in new stack
4192	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/1und1_Number1-000000b6", "MASTER CHANNEL: 1744651411.343 = 1744651411.343") in new stack
4193	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/1und1_Number1-000000b6", "0?return") in new stack
4194	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/1und1_Number1-000000b6", "__CRM_HANGUP=1") in new stack
4195	[2025-04-14 19:23:39] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/1und1_Number1-000000b6", "agi://127.0.0.1/sangomacrm.agi") in new stack
4196	[2025-04-14 19:23:40] VERBOSE[411699][C-000000a1] res_agi.c: <PJSIP/1und1_Number1-000000b6>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
4197	[2025-04-14 19:23:40] VERBOSE[411699][C-000000a1] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/1und1_Number1-000000b6", "") in new stack
4198	[2025-04-14 19:23:40] VERBOSE[411699][C-000000a1] app_stack.c: Spawn extension (dial-with-exten, 621, 1) exited non-zero on 'PJSIP/1und1_Number1-000000b6'
4199	[2025-04-14 19:23:40] VERBOSE[411699][C-000000a1] app_stack.c: PJSIP/1und1_Number1-000000b6 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

It’s a call from myself (cellphone) to a number on which missed call notifications are enabled. The telephone ran, I waited 3 seconds or so and hanged up. No email.

8

Yeah, that was what was needed. I need to look at the missedcallnotify.php script, it’s running but either nothing is happening or there’s no verbosity in the script for logging action. Not sure which it is without reviewing.

1 Like
9

Great thank you. Do you need anything else from my side or do I just wait if there is a new version coming up?