Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

Sign up for GitHub

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Jump to bottom

[bug]: Follow Me is not working at all. #285

Closed
mycupbloody opened this issue Jun 28, 2024 · 10 comments
Closed

[bug]: Follow Me is not working at all. #285

mycupbloody opened this issue Jun 28, 2024 · 10 comments
Labels
bug Something isn't working

Comments

@mycupbloody
Copy link

FreePBX Version

FreePBX 17

Issue Description

I was able to get follow me to work once. However, It stopped working and is not longer working. I can get it to ring the interval time, after that it going to VM. Also, Follow me working internal (If I dial the ext internal it'll ring to Follow me but not through an IVR option).

Operating Environment

| framework | 17.0.16 | Enabled | GPLv2+ | Sangoma |
Asterisk 21.3.1
+---------------------+-----------+---------+-------------+-----------+
| Module | Version | Status | License | Signature |
+---------------------+-----------+---------+-------------+-----------+
| accountcodepreserve | 17.0.0.1 | Enabled | GPLv2 | Sangoma |
| allowlist | 17.0.1.1 | Enabled | GPLv3+ | Sangoma |
| amd | 17.0.1 | Enabled | GPLv3+ | Sangoma |
| announcement | 17.0.2.1 | Enabled | GPLv3+ | Sangoma |
| api | 17.0.1.4 | Enabled | AGPLv3+ | Sangoma |
| areminder | 17.0.3.10 | Enabled | Commercial | Sangoma |
| arimanager | 17.0.1.1 | Enabled | GPLv3+ | Sangoma |
| asterisk-cli | 17.0.2 | Enabled | GPLv3+ | Sangoma |
| asteriskinfo | 17.0.2 | Enabled | GPLv3+ | Sangoma |
| backup | 17.0.5.54 | Enabled | GPLv3+ | Sangoma |
| blacklist | 17.0.1.2 | Enabled | GPLv3+ | Sangoma |
| broadcast | 17.0.1.6 | Enabled | Commercial | Sangoma |
| builtin | | Enabled | | Unsigned |
| bulkhandler | 17.0.5 | Enabled | GPLv3+ | Sangoma |
| calendar | 17.0.4.15 | Enabled | GPLv3+ | Sangoma |
| callaccounting | 17.0.5 | Enabled | Commercial+ | Sangoma |
| callback | 17.0.2.1 | Enabled | GPLv3+ | Sangoma |
| callerid | 17.0.1 | Enabled | Commercial | Sangoma |
| callforward | 17.0.1.6 | Enabled | AGPLv3+ | Sangoma |
| calllimit | 17.0.1.2 | Enabled | Commercial | Sangoma |
| callrecording | 17.0.3.6 | Enabled | AGPLv3+ | Sangoma |
| callwaiting | 17.0.3.4 | Enabled | GPLv3+ | Sangoma |
| cdr | 17.0.4.15 | Enabled | GPLv3+ | Sangoma |
| cdrpro | 17.0.3.17 | Enabled | Commercial | Sangoma |
| cel | 17.0.2.8 | Enabled | GPLv3+ | Sangoma |
| certman | 17.0.3.12 | Enabled | AGPLv3+ | Sangoma |
| cidlookup | 17.0.1.1 | Enabled | GPLv3+ | Sangoma |
| conferences | 17.0.3.2 | Enabled | GPLv3+ | Sangoma |
| conferencespro | 17.0.1.7 | Enabled | Commercial | Sangoma |
| configedit | 17.0.1.1 | Enabled | AGPLv3+ | Sangoma |
| contactmanager | 17.0.5.11 | Enabled | GPLv3+ | Sangoma |
| core | 17.0.9.72 | Enabled | GPLv3+ | Sangoma |
| cos | 17.0.1.1 | Enabled | Commercial | Sangoma |
| customappsreg | 17.0.1 | Enabled | GPLv3+ | Sangoma |
| customcontexts | 17.0.1.2 | Enabled | GPLv2+ | Sangoma |
| dashboard | 17.0.4.3 | Enabled | AGPLv3+ | Sangoma |
| daynight | 17.0.1.1 | Enabled | GPLv3+ | Sangoma |
| dictate | 17.0.1.2 | Enabled | GPLv3+ | Sangoma |
| directory | 17.0.1.1 | Enabled | GPLv3+ | Sangoma |
| disa | 17.0.3 | Enabled | AGPLv3+ | Sangoma |
| donotdisturb | 17.0.2.3 | Enabled | GPLv3+ | Sangoma |
| dynroute | 17.0.3.1 | Enabled | GPLv3+ | Sangoma |
| endpoint | 17.0.1.71 | Enabled | Commercial | Sangoma |
| extensionroutes | 17.0.1 | Enabled | Commercial | Sangoma |
| extensionsettings | 17.0.1 | Enabled | GPLv3+ | Sangoma |
| fax | 17.0.3.4 | Enabled | GPLv3+ | Sangoma |
| faxpro | 17.0.1.16 | Enabled | Commercial | Sangoma |
| featurecodeadmin | 17.0.2 | Enabled | GPLv3+ | Sangoma |
| filestore | 17.0.2.21 | Enabled | AGPLv3 | Sangoma |
| findmefollow | 17.0.4.8 | Enabled | GPLv3+ | Sangoma |
| firewall | 17.0.1.26 | Enabled | AGPLv3+ | Sangoma |
| framework | 17.0.16 | Enabled | GPLv2+ | Sangoma |
| hotelwakeup | 17.0.1.6 | Enabled | GPLv2 | Sangoma |
| iaxsettings | 17.0.1 | Enabled | AGPLv3 | Sangoma |
| infoservices | 17.0.1 | Enabled | GPLv2+ | Sangoma |
| ivr | 17.0.6 | Enabled | GPLv3+ | Sangoma |
| languages | 17.0.1 | Enabled | GPLv3+ | Sangoma |
| logfiles | 17.0.3.3 | Enabled | GPLv3+ | Sangoma |
| manager | 17.0.5 | Enabled | GPLv2+ | Sangoma |
| miscapps | 17.0.3 | Enabled | GPLv3+ | Sangoma |
| miscdests | 17.0.1.1 | Enabled | GPLv3+ | Sangoma |
| missedcall | 17.0.1.1 | Enabled | GPLv3+ | Sangoma |
| music | 17.0.4 | Enabled | GPLv3+ | Sangoma |
| outcnam | 17.0.1 | Enabled | GPLv3+ | Sangoma |
| outroutemsg | 17.0.1 | Enabled | GPLv3+ | Sangoma |
| paging | 17.0.3 | Enabled | GPLv3+ | Sangoma |
| pagingpro | 17.0.1.6 | Enabled | Commercial | Sangoma |
| parking | 17.0.2.4 | Enabled | GPLv3+ | Sangoma |
| parkpro | 17.0.1.4 | Enabled | Commercial | Sangoma |
| pbxmfa | 17.0.2 | Enabled | Commercial+ | Sangoma |
| phpinfo | 17.0.1 | Enabled | GPLv2+ | Sangoma |
| pinsets | 17.0.3.2 | Enabled | GPLv3+ | Sangoma |
| pinsetspro | 17.0.2 | Enabled | Commercial | Sangoma |
| pm2 | 17.0.3.2 | Enabled | AGPLv3+ | Sangoma |
| pms | 17.0.2.15 | Enabled | Commercial | Sangoma |
| presencestate | 17.0.2.4 | Enabled | GPLv3+ | Sangoma |
| printextensions | 17.0.1.2 | Enabled | GPLv3+ | Sangoma |
| queueprio | 17.0.1.4 | Enabled | GPLv3+ | Sangoma |
| queues | 17.0.1.9 | Enabled | GPLv2+ | Sangoma |
| queuestats | 17.0.1.7 | Enabled | Commercial | Sangoma |
| qxact_reports | 17.0.2 | Enabled | Commercial | Sangoma |
| recording_report | 17.0.3.8 | Enabled | Commercial | Sangoma |
| recordings | 17.0.2.2 | Enabled | GPLv3+ | Sangoma |
| restapps | 17.0.1.19 | Enabled | Commercial | Sangoma |
| ringgroups | 17.0.2.4 | Enabled | GPLv3+ | Sangoma |
| sangomaconnect | 17.0.1.35 | Enabled | Commercial | Sangoma |
| sangomacrm | 17.0.1.8 | Enabled | Commercial | Sangoma |
| sangomartapi | 17.0.1.26 | Enabled | Commercial | Sangoma |
| setcid | 17.0.1.2 | Enabled | GPLv3+ | Sangoma |
| sipsettings | 17.0.6.8 | Enabled | AGPLv3+ | Sangoma |
| sipstation | 17.0.3.3 | Enabled | Commercial | Sangoma |
| sms | 17.0.1.13 | Enabled | Commercial | Sangoma |
| smsplus | 17.0.3 | Enabled | Commercial | Sangoma |
| soundlang | 17.0.4.1 | Enabled | GPLv3+ | Sangoma |
| superfecta | 17.0.1.10 | Enabled | GPLv2+ | Sangoma |
| sysadmin | 17.0.1.89 | Enabled | Commercial | Sangoma |
| timeconditions | 17.0.1.17 | Enabled | GPLv3+ | Sangoma |
| tts | 17.0.1.1 | Enabled | GPLv3+ | Sangoma |
| ttsengines | 17.0.1 | Enabled | AGPLv3 | Sangoma |
| ucp | 17.0.4.19 | Enabled | AGPLv3+ | Sangoma |
| userman | 17.0.6.25 | Enabled | AGPLv3+ | Sangoma |
| vmblast | 17.0.2 | Enabled | GPLv3+ | Sangoma |
| vmnotify | 17.0.1.7 | Enabled | Commercial | Sangoma |
| voicemail | 17.0.5.22 | Enabled | GPLv3+ | Sangoma |
| voicemail_report | 17.0.1.1 | Enabled | Commercial | Sangoma |
| voipinnovations | 17.0.1.4 | Enabled | Commercial | Sangoma |
| vqplus | 17.0.1.16 | Enabled | Commercial | Sangoma |
| weakpasswords | 17.0.1 | Enabled | GPLv3+ | Sangoma |
| webcallback | 17.0.4 | Enabled | Commercial | Sangoma |
| webrtc | 17.0.2.2 | Enabled | GPLv3+ | Sangoma |
+---------------------+-----------+---------+-------------+-----------+
FW Console - FreePBX Utility 17.0.16

Relevant log output

[2024-06-28 10:09:44] VERBOSE[319688][C-0000001d] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/voipms-00000034", "MASTER CHANNEL: 1719583766.196 = 1719583766.196") in new stack
[2024-06-28 10:09:44] VERBOSE[319688][C-0000001d] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/voipms-00000034", "0?return") in new stack
[2024-06-28 10:09:44] VERBOSE[319688][C-0000001d] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/voipms-00000034", "__CRM_HANGUP=1") in new stack
[2024-06-28 10:09:44] VERBOSE[319688][C-0000001d] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/voipms-00000034", "agi://127.0.0.1/sangomacrm.agi") in new stack
[2024-06-28 10:09:44] VERBOSE[319688][C-0000001d] res_agi.c: <PJSIP/voipms-00000034>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2024-06-28 10:09:44] VERBOSE[319688][C-0000001d] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/voipms-00000034", "") in new stack
[2024-06-28 10:09:44] VERBOSE[319688][C-0000001d] app_stack.c: Spawn extension (macro-vm, s-BUSY, 3) exited non-zero on 'PJSIP/voipms-00000034'
[2024-06-28 10:09:44] VERBOSE[319688][C-0000001d] app_stack.c: PJSIP/voipms-00000034 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2024-06-28 10:14:03] VERBOSE[102983] res_pjsip/pjsip_configuration.c: Endpoint 2000 is now Unreachable
[2024-06-28 10:14:03] VERBOSE[102983] res_pjsip/pjsip_options.c: Contact 2000/sip:2000@184.75.238.186:20594;x-ast-orig-host=192.168.60.106:5060 is now Unreachable.  RTT: 0.000 msec
@mycupbloody mycupbloody added bug Something isn't working triage Triage labels Jun 28, 2024
@mycupbloody
Copy link
Author

[2024-06-28 09:26:07] ERROR[102983] res_pjsip_header_funcs.c: No headers had been previously added to this session.
[2024-06-28 10:04:28] ERROR[102983] res_pjsip_header_funcs.c: No headers had been previously added to this session.
[2024-06-28 10:09:41] ERROR[102983] res_pjsip_header_funcs.c: No headers had been previously added to this session.

@blazestudios97
Copy link

You need to provide the log output of the call hitting the IVR, the IVR option to ring the extension so we can see if what is actually happening. The log output provided isn't relevant.

@mycupbloody
Copy link
Author

You need to provide the log output of the call hitting the IVR, the IVR option to ring the extension so we can see if what is actually happening. The log output provided isn't relevant.

Can you tell me the proper command to do this?

@mycupbloody
Copy link
Author

Got it I believe.

[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-blkvm-clr:1] Set("PJSIP/voipms-00000036", "SHARED(BLKVM,PJSIP/voipms-00000036)=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-blkvm-clr:2] Set("PJSIP/voipms-00000036", "GOSUB_RETVAL=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-blkvm-clr:3] Return("PJSIP/voipms-00000036", "") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:53] Set("PJSIP/voipms-00000036", "DIALSTATUS=NOANSWER") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:54] GotoIf("PJSIP/voipms-00000036", "0?dohangup") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:55] Goto("PJSIP/voipms-00000036", "ext-local,2003,dest") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (ext-local,2003,4)
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@ext-local:4] Set("PJSIP/voipms-00000036", "__PICKUPMARK=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@ext-local:5] Gosub("PJSIP/voipms-00000036", "macro-vm,s,1(2003,NOANSWER,)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-vm:1] Gosub("PJSIP/voipms-00000036", "macro-user-callerid,s,1(SKIPTTL)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:1] Set("PJSIP/voipms-00000036", "TOUCH_MONITOR=1719586638.203") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:2] Set("PJSIP/voipms-00000036", "CHANCONTEXT=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:3] Progress("PJSIP/voipms-00000036", "") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:4] Set("PJSIP/voipms-00000036", "CHANCONTEXT=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:5] Set("PJSIP/voipms-00000036", "CHANEXTENCONTEXT=voipms-00000036") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:6] Set("PJSIP/voipms-00000036", "CHANEXTEN=voipms-00000036") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:7] Set("PJSIP/voipms-00000036", "CALLERID(number)=3369040339") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:8] Set("PJSIP/voipms-00000036", "AMPUSER=3369040339") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:9] Set("PJSIP/voipms-00000036", "HOTDESCKCHAN=voipms-00000036") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:10] Set("PJSIP/voipms-00000036", "HOTDESKEXTEN=voipms") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:11] Set("PJSIP/voipms-00000036", "HOTDESKCALL=0") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:12] ExecIf("PJSIP/voipms-00000036", "0?Set(HOTDESKCALL=1)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("PJSIP/voipms-00000036", "0?Set(CALLERID(name)=)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:14] GotoIf("PJSIP/voipms-00000036", "0?report") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:15] ExecIf("PJSIP/voipms-00000036", "0?Set(REALCALLERIDNUM=3369040339)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:16] Set("PJSIP/voipms-00000036", "AMPUSER=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("PJSIP/voipms-00000036", "0?limit") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:18] Set("PJSIP/voipms-00000036", "AMPUSERCIDNAME=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:19] ExecIf("PJSIP/voipms-00000036", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:20] GotoIf("PJSIP/voipms-00000036", "1?report") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:29] NoOp("PJSIP/voipms-00000036", "Macro depricated!! To keep the same line numbers") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:30] NoOp("PJSIP/voipms-00000036", "Macro depricated !! To keep the same line numbers") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("PJSIP/voipms-00000036", "1?continue") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (macro-user-callerid,s,50)
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:50] Set("PJSIP/voipms-00000036", "CALLERID(number)=3369040339") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:51] Set("PJSIP/voipms-00000036", "CALLERID(name)=KERNERSVILLE NC") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:52] GotoIf("PJSIP/voipms-00000036", "0?cnum") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:53] Set("PJSIP/voipms-00000036", "__MCNUM=3369040339") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:54] Set("PJSIP/voipms-00000036", "__MCNAME=KERNERSVILLE NC") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:55] Set("PJSIP/voipms-00000036", "__MCEXTEN=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:56] Set("PJSIP/voipms-00000036", "__MCORGCHAN=PJSIP/voipms-00000036") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:57] Set("PJSIP/voipms-00000036", "CDR(cnam)=KERNERSVILLE NC") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:58] Set("PJSIP/voipms-00000036", "CDR(cnum)=3369040339") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-user-callerid:59] Return("PJSIP/voipms-00000036", "") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-vm:2] Set("PJSIP/voipms-00000036", "VMGAIN=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-vm:3] Gosub("PJSIP/voipms-00000036", "macro-blkvm-check,s,1()") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-blkvm-check:1] Set("PJSIP/voipms-00000036", "GOSUB_RETVAL=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf("PJSIP/voipms-00000036", "0?Set(GOSUB_RETVAL=TRUE)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-blkvm-check:3] Return("PJSIP/voipms-00000036", "") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-vm:4] GotoIf("PJSIP/voipms-00000036", "1?vmx,1") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (macro-vm,vmx,1)
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:1] Set("PJSIP/voipms-00000036", "__EXTTOCALL=2003") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:2] Set("PJSIP/voipms-00000036", "__CRM_VOICEMAIL=2003") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:3] Set("PJSIP/voipms-00000036", "MEXTEN=2003") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:4] Set("PJSIP/voipms-00000036", "MMODE=NOANSWER") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:5] Set("PJSIP/voipms-00000036", "RETVM=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:6] Set("PJSIP/voipms-00000036", "MODE=unavail") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:7] Gosub("PJSIP/voipms-00000036", "macro-get-vmcontext,s,1(2003)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-get-vmcontext:1] Set("PJSIP/voipms-00000036", "VMCONTEXT=default") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("PJSIP/voipms-00000036", "0?200:300") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("PJSIP/voipms-00000036", "") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-get-vmcontext:301] Return("PJSIP/voipms-00000036", "") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:8] Set("PJSIP/voipms-00000036", "MODE=unavail") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:9] NoOp("PJSIP/voipms-00000036", "MODE IS: unavail") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:10] GotoIf("PJSIP/voipms-00000036", "1?chknomsg") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (macro-vm,vmx,12)
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:12] GotoIf("PJSIP/voipms-00000036", "0?s-NOANSWER,1") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:13] GotoIf("PJSIP/voipms-00000036", "1?notdirect") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (macro-vm,vmx,15)
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:15] NoOp("PJSIP/voipms-00000036", "Checking if ext 2003 is enabled: ") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:16] GotoIf("PJSIP/voipms-00000036", "1?s-NOANSWER,1") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (macro-vm,s-NOANSWER,1)
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s-NOANSWER@macro-vm:1] Gosub("PJSIP/voipms-00000036", "macro-get-vmcontext,s,1(2003)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-get-vmcontext:1] Set("PJSIP/voipms-00000036", "VMCONTEXT=default") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf("PJSIP/voipms-00000036", "0?200:300") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp("PJSIP/voipms-00000036", "") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-get-vmcontext:301] Return("PJSIP/voipms-00000036", "") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s-NOANSWER@macro-vm:2] VoiceMail("PJSIP/voipms-00000036", "2003@default,u") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] file.c: <PJSIP/voipms-00000036> Playing '/var/spool/asterisk/voicemail/default/2003/unavail.slin' (language 'en')
[2024-06-28 10:57:47] VERBOSE[331297][C-0000001e] file.c: <PJSIP/voipms-00000036> Playing 'vm-intro.ulaw' (language 'en')
[2024-06-28 10:57:53] VERBOSE[331297][C-0000001e] file.c: <PJSIP/voipms-00000036> Playing 'beep.ulaw' (language 'en')
[2024-06-28 10:57:53] VERBOSE[331297][C-0000001e] app_voicemail.c: Recording the message
[2024-06-28 10:57:53] VERBOSE[331297][C-0000001e] app.c: x=0, open writing: /var/spool/asterisk/voicemail/default/2003/tmp/vVXpd0 format: wav, 0x7f7dd00e3be0
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] app.c: User hung up
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] pbx.c: Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on 'PJSIP/voipms-00000036'
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] app_stack.c: PJSIP/voipms-00000036 Internal Gosub(crm-hangup,s,1) start
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:1] NoOp("PJSIP/voipms-00000036", "Sending Hangup to CRM") in new stack
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:2] NoOp("PJSIP/voipms-00000036", "HANGUP CAUSE: 16") in new stack
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:3] ExecIf("PJSIP/voipms-00000036", "1?Set(__CRM_VOICEMAIL=SUCCESS)") in new stack
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:4] NoOp("PJSIP/voipms-00000036", "MASTER CHANNEL: 1719586638.203 = 1719586638.203") in new stack
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:5] GotoIf("PJSIP/voipms-00000036", "0?return") in new stack
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:6] Set("PJSIP/voipms-00000036", "__CRM_HANGUP=1") in new stack
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:7] AGI("PJSIP/voipms-00000036", "agi://127.0.0.1/sangomacrm.agi") in new stack
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] res_agi.c: <PJSIP/voipms-00000036>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:8] Return("PJSIP/voipms-00000036", "") in new stack
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] app_stack.c: Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on 'PJSIP/voipms-00000036'
[2024-06-28 10:57:56] VERBOSE[331297][C-0000001e] app_stack.c: PJSIP/voipms-00000036 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

2nd log for ext 2003

[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [1@ivr-3:2] Goto("PJSIP/voipms-00000036", "from-did-direct,2003,1") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (from-did-direct,2003,1)
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@from-did-direct:1] GotoIf("PJSIP/voipms-00000036", "0?ext-local,*2003,1") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@from-did-direct:2] GotoIf("PJSIP/voipms-00000036", "0?ext-local,2003,1:followme-check,2003,1") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (followme-check,2003,1)
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-check:1] Gosub("PJSIP/voipms-00000036", "followme-sub,2003,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:1] Set("PJSIP/voipms-00000036", "__FMFM=TRUE") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:2] GotoIf("PJSIP/voipms-00000036", "0?skipclid") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:3] Gosub("PJSIP/voipms-00000036", "macro-user-callerid,s,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:4] Set("PJSIP/voipms-00000036", "DIAL_OPTIONS=HhTtrI") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:5] Set("PJSIP/voipms-00000036", "CONNECTEDLINE(num,i)=2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:6] Gosub("PJSIP/voipms-00000036", "sub-presencestate-display,s,1(2003)") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:7] Set("PJSIP/voipms-00000036", "CONNECTEDLINE(name)=Cory(Available)") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:8] Set("PJSIP/voipms-00000036", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:9] Set("PJSIP/voipms-00000036", "__EXTTOCALL=2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:10] Set("PJSIP/voipms-00000036", "__PICKUPMARK=2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:11] Gosub("PJSIP/voipms-00000036", "macro-blkvm-setifempty,s,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:12] GotoIf("PJSIP/voipms-00000036", "0?skipov") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:13] Gosub("PJSIP/voipms-00000036", "macro-blkvm-set,s,1(reset)") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:14] Set("PJSIP/voipms-00000036", "__NODEST=") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:15] Set("PJSIP/voipms-00000036", "RRNODEST=") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:16] Set("PJSIP/voipms-00000036", "__NODEST=2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:17] GosubIf("PJSIP/voipms-00000036", "0?sub-fmsetcid,s,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:18] GotoIf("PJSIP/voipms-00000036", "1?skipprepend") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (followme-sub,2003,20)
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:20] Set("PJSIP/voipms-00000036", "RecordMethod=Group") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:21] Gosub("PJSIP/voipms-00000036", "sub-record-check,s,1(exten,2003,)") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [exten@sub-record-check:1] NoOp("PJSIP/voipms-00000036", "Exten Recording Check between 3369040339 and 2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [exten@sub-record-check:11] Gosub("PJSIP/voipms-00000036", "recordcheck,1(dontcare,external,2003)") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:22] GotoIf("PJSIP/voipms-00000036", "1?skipdring") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (followme-sub,2003,25)
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:25] Set("PJSIP/voipms-00000036", "STRATEGY=ringallv2-prim") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:26] Set("PJSIP/voipms-00000036", "__RVOL=") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:27] GotoIf("PJSIP/voipms-00000036", "1?skipsimple") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (followme-sub,2003,30)
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:30] Set("PJSIP/voipms-00000036", "RingGroupMethod=ringallv2-prim") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:31] Set("PJSIP/voipms-00000036", "_FMGRP=2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:32] GotoIf("PJSIP/voipms-00000036", "1?DIALGRP") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (followme-sub,2003,36)
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:36] ExecIf("PJSIP/voipms-00000036", "1?Set(DOPTS=HhTtrI):Set(DOPTS=m(Ring)HhTtI)") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:37] Set("PJSIP/voipms-00000036", "__ALT_CONFIRM_MSG=") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:38] GotoIf("PJSIP/voipms-00000036", "0?doconfirm") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:39] GotoIf("PJSIP/voipms-00000036", "1?ringallv21") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (followme-sub,2003,42)
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:42] Gosub("PJSIP/voipms-00000036", "macro-dial,s,1(27,HhTtrI,2003-3368094717#)") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] res_agi.c: agi://127.0.0.1/dialparties.agi: Added extension 2003 to extension map
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 2003 cf is disabled
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 2003 do not disturb is disabled
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] res_agi.c: agi://127.0.0.1/dialparties.agi: Extension 2003 has ExtensionState: 0
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] res_agi.c: agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/2003 to 3369040339
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] res_agi.c: agi://127.0.0.1/dialparties.agi: Filtered ARG3: 2003-3368094717
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-dial:13] NoOp("PJSIP/voipms-00000036", "ds= Local/FMPR-2003@from-internal&Local/FMGL-3368094717#@from-internal,27,HhtrIU(macro-auto-blkvm) ") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-dial:15] Set("PJSIP/voipms-00000036", "ds=Local/FMPR-2003@from-internal&Local/FMGL-3368094717#@from-internal,27,HhtrIU(macro-auto-blkvm)") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-dial:16] NoOp("PJSIP/voipms-00000036", "ds= Local/FMPR-2003@from-internal&Local/FMGL-3368094717#@from-internal,27,HhtrIU(macro-auto-blkvm)") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-dial:20] Set("PJSIP/voipms-00000036", "__EXTTOCALL=2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-dial:21] Set("PJSIP/voipms-00000036", "__MCEXTTOCALL=2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-dial:22] NoOp("PJSIP/voipms-00000036", "Working with 2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-dial:29] ExecIf("PJSIP/voipms-00000036", "0?Set(ds=Local/FMPR-2003@from-internal&Local/FMGL-3368094717#@from-internal,27,HhtrIU(macro-auto-blkvm)g)") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@macro-dial:30] Dial("PJSIP/voipms-00000036", "Local/FMPR-2003@from-internal&Local/FMGL-3368094717#@from-internal,27,HhtrIU(macro-auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] app_stack.c: Local/FMPR-2003@from-internal-00000023;1 Internal Gosub(func-apply-sipheaders,s,1) start
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("Local/FMPR-2003@from-internal-00000023;1", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("Local/FMPR-2003@from-internal-00000023;1", "Applying SIP Headers to channel Local/FMPR-2003@from-internal-00000023;1") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@func-apply-sipheaders:3] Set("Local/FMPR-2003@from-internal-00000023;1", "localchan=FMPR-2003@from-internal-00000023;1") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@func-apply-sipheaders:4] Set("Local/FMPR-2003@from-internal-00000023;1", "DialMCEXT=FMPR") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@func-apply-sipheaders:5] Set("Local/FMPR-2003@from-internal-00000023;1", "CHANNEL(hangup_handler_push)=app-missedcall-hangup,FMPR,1") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@func-apply-sipheaders:6] Set("Local/FMPR-2003@from-internal-00000023;1", "TECH=Local") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@func-apply-sipheaders:7] Set("Local/FMPR-2003@from-internal-00000023;1", "SIPHEADERKEYS=") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@func-apply-sipheaders:8] While("Local/FMPR-2003@from-internal-00000023;1", "0") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@func-apply-sipheaders:15] Return("Local/FMPR-2003@from-internal-00000023;1", "") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] app_stack.c: Spawn extension (from-internal, s, 1) exited non-zero on 'Local/FMPR-2003@from-internal-00000023;1'
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] app_stack.c: Local/FMPR-2003@from-internal-00000023;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] app_dial.c: Called Local/FMPR-2003@from-internal
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [FMPR-2003@from-internal:1] Set("Local/FMPR-2003@from-internal-00000023;2", "CDR_PROP(disable)=true") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [FMPR-2003@from-internal:2] Set("Local/FMPR-2003@from-internal-00000023;2", "RingGroupMethod=") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [FMPR-2003@from-internal:3] Set("Local/FMPR-2003@from-internal-00000023;2", "USE_CONFIRMATION=") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [FMPR-2003@from-internal:4] Set("Local/FMPR-2003@from-internal-00000023;2", "RINGGROUP_INDEX=") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [FMPR-2003@from-internal:5] Gosub("Local/FMPR-2003@from-internal-00000023;2", "macro-simple-dial,s,1(2003,27)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:1] Set("Local/FMPR-2003@from-internal-00000023;2", "__EXTTOCALL=2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:2] Set("Local/FMPR-2003@from-internal-00000023;2", "RT=27") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:3] Set("Local/FMPR-2003@from-internal-00000023;2", "CFUEXT=") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:4] Set("Local/FMPR-2003@from-internal-00000023;2", "CFBEXT=") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:5] Set("Local/FMPR-2003@from-internal-00000023;2", "CWI_TMP=") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:6] Gosub("Local/FMPR-2003@from-internal-00000023;2", "macro-dial-one,s,1(27,HhTtr,2003)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:1] Set("Local/FMPR-2003@from-internal-00000023;2", "DEXTEN=2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:2] Set("Local/FMPR-2003@from-internal-00000023;2", "__CRM_SOURCE=3369040339") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:3] Set("Local/FMPR-2003@from-internal-00000023;2", "__MCEXTTOCALL=2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:4] Set("Local/FMPR-2003@from-internal-00000023;2", "__MCMULTI=a591024321c5e2bdbd23ed35f0574dde") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:5] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(__EXTTOCALL=2003)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:6] Set("Local/FMPR-2003@from-internal-00000023;2", "DIALSTATUS_CW=") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:7] GosubIf("Local/FMPR-2003@from-internal-00000023;2", "0?screen,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:8] GosubIf("Local/FMPR-2003@from-internal-00000023;2", "0?cf,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:9] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "1?skip1") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:12] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "0?nodial") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:13] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "0?continue") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:14] Set("Local/FMPR-2003@from-internal-00000023;2", "EXTHASCW=ENABLED") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:15] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "0?next1:cwinusebusy") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:27] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "0?next3:continue") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:29] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "0?nodial") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:30] GosubIf("Local/FMPR-2003@from-internal-00000023;2", "1?dstring,1():dlocal,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:1] Set("Local/FMPR-2003@from-internal-00000023;2", "DSTRING=") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:2] Set("Local/FMPR-2003@from-internal-00000023;2", "DEVICES=2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Return()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(DEVICES=003)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:5] Set("Local/FMPR-2003@from-internal-00000023;2", "LOOPCNT=1") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:6] Set("Local/FMPR-2003@from-internal-00000023;2", "ITER=1") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:7] Set("Local/FMPR-2003@from-internal-00000023;2", "THISDIAL=VIRTUAL/2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:8] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "1?docheck") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "0?skipset") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:15] Set("Local/FMPR-2003@from-internal-00000023;2", "DSTRING=VIRTUAL/2003&") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:16] Set("Local/FMPR-2003@from-internal-00000023;2", "ITER=2") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "0?begin") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Return()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:19] Set("Local/FMPR-2003@from-internal-00000023;2", "DSTRING=VIRTUAL/2003") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [dstring@macro-dial-one:20] Return("Local/FMPR-2003@from-internal-00000023;2", "") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:31] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "0?nodial") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:32] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "0?skiptrace") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:33] GosubIf("Local/FMPR-2003@from-internal-00000023;2", "1?ctset,1():ctclear,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [ctset@macro-dial-one:1] Set("Local/FMPR-2003@from-internal-00000023;2", "DB(CALLTRACE/2003)=3369040339") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [ctset@macro-dial-one:2] Return("Local/FMPR-2003@from-internal-00000023;2", "") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:34] Set("Local/FMPR-2003@from-internal-00000023;2", "D_OPTIONS=HhTtrU(macro-auto-blkvm)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:35] GosubIf("Local/FMPR-2003@from-internal-00000023;2", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:36] NoOp("Local/FMPR-2003@from-internal-00000023;2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:37] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(ALERT_INFO=)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:38] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(ALERT_INFO=)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:39] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(ALERT_INFO=)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:40] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:41] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:42] GosubIf("Local/FMPR-2003@from-internal-00000023;2", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:43] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(CHANNEL(musicclass)=)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:44] GosubIf("Local/FMPR-2003@from-internal-00000023;2", "0?qwait,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:45] Set("Local/FMPR-2003@from-internal-00000023;2", "__CWIGNORE=") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:46] Set("Local/FMPR-2003@from-internal-00000023;2", "__KEEPCID=TRUE") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:47] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "0?usegoto,1") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:48] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "1?godial") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:53] Gosub("Local/FMPR-2003@from-internal-00000023;2", "macro-dialout-one-predial-hook,s,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] Return("Local/FMPR-2003@from-internal-00000023;2", "") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:54] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "1?Set(D_OPTIONS=HhtrU(macro-auto-blkvm)I)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:55] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:56] NoOp("Local/FMPR-2003@from-internal-00000023;2", "") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:57] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(D_OPTIONS=HhtrU(macro-auto-blkvm)I)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:58] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(D_OPTIONS=HhtrU(macro-auto-blkvm)Ig)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:59] Dial("Local/FMPR-2003@from-internal-00000023;2", "VIRTUAL/2003,27,HhtrU(macro-auto-blkvm)Ib(func-apply-sipheaders^s^1)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:60] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Return()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:61] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(DIALSTATUS=)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:62] GosubIf("Local/FMPR-2003@from-internal-00000023;2", "0?s-CHANUNAVAIL,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-dial-one:63] Return("Local/FMPR-2003@from-internal-00000023;2", "") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:7] Set("Local/FMPR-2003@from-internal-00000023;2", "__CWIGNORE=") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:8] Set("Local/FMPR-2003@from-internal-00000023;2", "PR_DIALSTATUS=CHANUNAVAIL") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:9] GosubIf("Local/FMPR-2003@from-internal-00000023;2", "0?docfu,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:10] GosubIf("Local/FMPR-2003@from-internal-00000023;2", "0?docfb,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:11] Set("Local/FMPR-2003@from-internal-00000023;2", "DIALSTATUS=CHANUNAVAIL") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-simple-dial:12] Goto("Local/FMPR-2003@from-internal-00000023;2", "s-CHANUNAVAIL,1") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s-CHANUNAVAIL@macro-simple-dial:1] NoOp("Local/FMPR-2003@from-internal-00000023;2", "Extension is reporting s-CHANUNAVAIL") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s-CHANUNAVAIL@macro-simple-dial:2] Return("Local/FMPR-2003@from-internal-00000023;2", "") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [FMPR-2003@from-internal:6] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(SHARED(FM_DND,PJSIP/voipms-00000036)=DND)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [FMPR-2003@from-internal:7] Hangup("Local/FMPR-2003@from-internal-00000023;2", "") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Spawn extension (from-internal, FMPR-2003, 7) exited non-zero on 'Local/FMPR-2003@from-internal-00000023;2'
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [h@from-internal:1] Gosub("Local/FMPR-2003@from-internal-00000023;2", "macro-hangupcall,s,1") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-hangupcall:1] Set("Local/FMPR-2003@from-internal-00000023;2", "__MCVMSTATUS=") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-hangupcall:2] Gosub("Local/FMPR-2003@from-internal-00000023;2", "app-missedcall-hangup,s,1()") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp("Local/FMPR-2003@from-internal-00000023;2", "Dialed: s") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp("Local/FMPR-2003@from-internal-00000023;2", "Caller: ") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@app-missedcall-hangup:3] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "1?exit") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@app-missedcall-hangup:8] Return("Local/FMPR-2003@from-internal-00000023;2", "") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-hangupcall:3] UserEvent("Local/FMPR-2003@from-internal-00000023;2", "MES,RTPAUDIOQOSMESBRIDGED:,RTPAUDIOQOSMES:") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-hangupcall:4] GotoIf("Local/FMPR-2003@from-internal-00000023;2", "1?theend") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-hangupcall:6] ExecIf("Local/FMPR-2003@from-internal-00000023;2", "0?Set(CDR(recordingfile)=)") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Executing [s@macro-hangupcall:7] Hangup("Local/FMPR-2003@from-internal-00000023;2", "") in new stack
[2024-06-28 10:57:28] VERBOSE[331374][C-0000001e] pbx.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on 'Local/FMPR-2003@from-internal-00000023;2'
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] app_stack.c: Local/FMPR-2003@from-internal-00000023;1 Internal Gosub(app-missedcall-hangup,FMPR,1) start
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [FMPR@app-missedcall-hangup:1] NoOp("Local/FMPR-2003@from-internal-00000023;1", "Dialed: FMPR") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [FMPR@app-missedcall-hangup:2] NoOp("Local/FMPR-2003@from-internal-00000023;1", "Caller: ") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [FMPR@app-missedcall-hangup:3] GotoIf("Local/FMPR-2003@from-internal-00000023;1", "0?exit") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [FMPR@app-missedcall-hangup:4] Set("Local/FMPR-2003@from-internal-00000023;1", "EXTENNUM=FMPR") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [FMPR@app-missedcall-hangup:5] Set("Local/FMPR-2003@from-internal-00000023;1", "FEXTENNUM=FMPR") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [FMPR@app-missedcall-hangup:6] GotoIf("Local/FMPR-2003@from-internal-00000023;1", "0?exit") in new stack
[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [FMPR@app-missedcall-hangup:7] AGI("Local/FMPR-2003@from-internal-00000023;1", "agi://127.0.0.1/missedcallnotify.php,FMPR,,FMPR,0,,Local/FMPR-2003@from-internal-00000023;1,,,,TRUE") in new stack
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] res_agi.c: <Local/FMPR-2003@from-internal-00000023;1>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] pbx.c: Executing [FMPR@app-missedcall-hangup:8] Return("Local/FMPR-2003@from-internal-00000023;1", "") in new stack
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] app_stack.c: Spawn extension (from-internal, s, 1) exited non-zero on 'Local/FMPR-2003@from-internal-00000023;1'
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] app_stack.c: Local/FMPR-2003@from-internal-00000023;1 Internal Gosub(app-missedcall-hangup,FMPR,1) complete GOSUB_RETVAL=
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] app_stack.c: Local/FMPR-2003@from-internal-00000023;1 Internal Gosub(crm-hangup,s,1) start
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:1] NoOp("Local/FMPR-2003@from-internal-00000023;1", "Sending Hangup to CRM") in new stack
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:2] NoOp("Local/FMPR-2003@from-internal-00000023;1", "HANGUP CAUSE: 66") in new stack
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:3] ExecIf("Local/FMPR-2003@from-internal-00000023;1", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:4] NoOp("Local/FMPR-2003@from-internal-00000023;1", "MASTER CHANNEL: 1719586648.204 = 1719586638.203") in new stack
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:5] GotoIf("Local/FMPR-2003@from-internal-00000023;1", "1?return") in new stack
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] pbx.c: Executing [s@crm-hangup:8] Return("Local/FMPR-2003@from-internal-00000023;1", "") in new stack
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] app_stack.c: Spawn extension (from-internal, s, 1) exited non-zero on 'Local/FMPR-2003@from-internal-00000023;1'
[2024-06-28 10:57:29] VERBOSE[331297][C-0000001e] app_stack.c: Local/FMPR-2003@from-internal-00000023;1 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:43] Goto("PJSIP/voipms-00000036", "nextstep") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (followme-sub,2003,48)
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:48] Set("PJSIP/voipms-00000036", "RingGroupMethod=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:49] GotoIf("PJSIP/voipms-00000036", "0?nodest") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:50] Set("PJSIP/voipms-00000036", "__NODEST=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:51] Set("PJSIP/voipms-00000036", "__PICKUPMARK=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:52] Gosub("PJSIP/voipms-00000036", "macro-blkvm-clr,s,1()") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:53] Set("PJSIP/voipms-00000036", "DIALSTATUS=NOANSWER") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:54] GotoIf("PJSIP/voipms-00000036", "0?dohangup") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:55] Goto("PJSIP/voipms-00000036", "ext-local,2003,dest") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx_builtins.c: Goto (ext-local,2003,4)
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@ext-local:4] Set("PJSIP/voipms-00000036", "__PICKUPMARK=") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@ext-local:5] Gosub("PJSIP/voipms-00000036", "macro-vm,s,1(2003,NOANSWER,)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:1] Set("PJSIP/voipms-00000036", "__EXTTOCALL=2003") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:2] Set("PJSIP/voipms-00000036", "__CRM_VOICEMAIL=2003") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:3] Set("PJSIP/voipms-00000036", "MEXTEN=2003") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:7] Gosub("PJSIP/voipms-00000036", "macro-get-vmcontext,s,1(2003)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [vmx@macro-vm:15] NoOp("PJSIP/voipms-00000036", "Checking if ext 2003 is enabled: ") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s-NOANSWER@macro-vm:1] Gosub("PJSIP/voipms-00000036", "macro-get-vmcontext,s,1(2003)") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] pbx.c: Executing [s-NOANSWER@macro-vm:2] VoiceMail("PJSIP/voipms-00000036", "2003@default,u") in new stack
[2024-06-28 10:57:36] VERBOSE[331297][C-0000001e] file.c: <PJSIP/voipms-00000036> Playing '/var/spool/asterisk/voicemail/default/2003/unavail.slin' (language 'en')
[2024-06-28 10:57:53] VERBOSE[331297][C-0000001e] app.c: x=0, open writing: /var/spool/asterisk/voicemail/default/2003/tmp/vVXpd0 format: wav, 0x7f7dd00e3be0

@blazestudios97
Copy link

Follow Me is executing as it should. This shows it using 2003 and 3368094717 as being called.

[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:42] Gosub("PJSIP/voipms-00000036", "macro-dial,s,1(27,HhTtrI,2003-3368094717#)") in new stack

Your problem is a configuration problem. It looks like 2003 is a virtual extension and you have the ring strategy set to ringallv2-prim which means if the primary extension (first in list) is in use/busy/unavailable do not call the rest of the list. Since 2003 is virtual and there's no way to call a virtual extension it comes back as CHANUNAVAIL status which will stop Follow Me from calling the rest of the list.

Is there a reason 2003 is in the list? If not, remove it. If it needs to be there for some reason, change the strategy and don't use *prim versions as they will do what I just described.

@mycupbloody
Copy link
Author

Follow Me is executing as it should. This shows it using 2003 and 3368094717 as being called.

[2024-06-28 10:57:28] VERBOSE[331297][C-0000001e] pbx.c: Executing [2003@followme-sub:42] Gosub("PJSIP/voipms-00000036", "macro-dial,s,1(27,HhTtrI,2003-3368094717#)") in new stack

Your problem is a configuration problem. It looks like 2003 is a virtual extension and you have the ring strategy set to ringallv2-prim which means if the primary extension (first in list) is in use/busy/unavailable do not call the rest of the list. Since 2003 is virtual and there's no way to call a virtual extension it comes back as CHANUNAVAIL status which will stop Follow Me from calling the rest of the list.

Is there a reason 2003 is in the list? If not, remove it. If it needs to be there for some reason, change the strategy and don't use *prim versions as they will do what I just described.

I need a virtual extension so the external number can have a VM box, get the VM attachment in email and ring to cell phone and leave the VM on the cell phone

@blazestudios97
Copy link

OK, you don't need 2003 in the Follow Me list. You can't call 2003, so remove 2003 from the list. As long as the ring time is short enough to not end up in the external numbers voicemail or not confirming the call (if call confirm is enabled) the system will send the call to voicemail when not answered.

The issue here is you're trying to dial a non-existent device that fails when calls are sent to it. Because it fails and the current ring strategy it doesn't attempt any other numbers in the list.

@mycupbloody
Copy link
Author

OK, you don't need 2003 in the Follow Me list. You can't call 2003, so remove 2003 from the list. As long as the ring time is short enough to not end up in the external numbers voicemail or not confirming the call (if call confirm is enabled) the system will send the call to voicemail when not answered.

The issue here is you're trying to dial a non-existent device that fails when calls are sent to it. Because it fails and the current ring strategy it doesn't attempt any other numbers in the list.

I removed the ext but it still will not ring through. BTW thank you for your help so far. It is greatly appreciated.
Screenshot 2024-06-28 123708

@msanthosh18
Copy link

msanthosh18 commented Jul 1, 2024 •
edited
Loading

Hi @mycupbloody ,
As per the above call logs, it dialing virtual number 2003 and going to VM without dialing the external number,

Can you set Initial Ring Time as 0 and try, if its still an issue please share complete asterisk cli logs

@msanthosh18 msanthosh18 added the needs-information Further information is requested label Jul 3, 2024
@kguptasangoma
Copy link
Member

Closing due to no response, please re-open the jira if you have any further query. thanks

@kguptasangoma kguptasangoma removed triage Triage needs-information Further information is requested labels Sep 3, 2024
Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Assignees
No one assigned
Labels
bug Something isn't working
Projects
None yet
Milestone
No milestone
Development

No branches or pull requests
4 participants
@blazestudios97 @kguptasangoma @mycupbloody @msanthosh18