3011 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [119@followme-sub:53] GotoIf(“SIP/voipms-00000925”, “0?dohangup”) in new stack
3012 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [119@followme-sub:54] Goto(“SIP/voipms-00000925”, “ext-local,119,dest”) in new stack
3013 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx_builtins.c: Goto (ext-local,119,4)
3014 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [119@ext-local:4] Set(“SIP/voipms-00000925”, “__PICKUPMARK=”) in new stack
3015 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [119@ext-local:5] Macro(“SIP/voipms-00000925”, “vm,119,NOANSWER,”) in new stack
3016 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-vm:1] Macro(“SIP/voipms-00000925”, “user-callerid,SKIPTTL”) in new stack
3017 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/voipms-00000925”, “TOUCH_MONITOR=1653667064.18005”) in new stack
3018 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/voipms-00000925”, “CHANCONTEXT=”) in new stack
3019 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:3] Set(“SIP/voipms-00000925”, “CHANCONTEXT=”) in new stack
3020 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:4] Set(“SIP/voipms-00000925”, “CHANEXTENCONTEXT=voipms-00000925”) in new stack
3021 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/voipms-00000925”, “CHANEXTEN=voipms-00000925”) in new stack
3022 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:6] Set(“SIP/voipms-00000925”, “CALLERID(number)=3069147081”) in new stack
3023 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/voipms-00000925”, “AMPUSER=3069147081”) in new stack
3024 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:8] Set(“SIP/voipms-00000925”, “HOTDESCKCHAN=voipms-00000925”) in new stack
3025 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:9] Set(“SIP/voipms-00000925”, “HOTDESKEXTEN=voipms”) in new stack
3026 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:10] Set(“SIP/voipms-00000925”, “HOTDESKCALL=0”) in new stack
3027 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:11] ExecIf(“SIP/voipms-00000925”, “0?Set(HOTDESKCALL=1)”) in new stack
3028 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:12] ExecIf(“SIP/voipms-00000925”, “0?Set(CALLERID(name)=)”) in new stack
3029 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:13] GotoIf(“SIP/voipms-00000925”, “0?report”) in new stack
3030 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:14] ExecIf(“SIP/voipms-00000925”, “0?Set(REALCALLERIDNUM=3069147081)”) in new stack
3031 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:15] Set(“SIP/voipms-00000925”, “AMPUSER=”) in new stack
3032 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:16] GotoIf(“SIP/voipms-00000925”, “0?limit”) in new stack
3033 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:17] Set(“SIP/voipms-00000925”, “AMPUSERCIDNAME=”) in new stack
3034 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:18] ExecIf(“SIP/voipms-00000925”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
3035 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:19] GotoIf(“SIP/voipms-00000925”, “1?report”) in new stack
3036 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx_builtins.c: Goto (macro-user-callerid,s,28)
3037 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:28] NoOp(“SIP/voipms-00000925”, “Macro Depth is 2”) in new stack
3038 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:29] GotoIf(“SIP/voipms-00000925”, “1?report2:macroerror”) in new stack
3039 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx_builtins.c: Goto (macro-user-callerid,s,30)
3040 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:30] GotoIf(“SIP/voipms-00000925”, “1?continue”) in new stack
3041 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx_builtins.c: Goto (macro-user-callerid,s,49)
3042 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:49] Set(“SIP/voipms-00000925”, “CALLERID(number)=3069147081”) in new stack
3043 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:50] Set(“SIP/voipms-00000925”, “CALLERID(name)=+13069147081”) in new stack
3044 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:51] GotoIf(“SIP/voipms-00000925”, “0?cnum”) in new stack
3045 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:52] Set(“SIP/voipms-00000925”, “CDR(cnam)=+13069147081”) in new stack
3046 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:53] Set(“SIP/voipms-00000925”, “CDR(cnum)=3069147081”) in new stack
3047 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-user-callerid:54] Set(“SIP/voipms-00000925”, “CHANNEL(language)=en”) in new stack
3048 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-vm:2] Set(“SIP/voipms-00000925”, “VMGAIN=”) in new stack
3049 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-vm:3] Macro(“SIP/voipms-00000925”, “blkvm-check,”) in new stack
3050 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-blkvm-check:1] Set(“SIP/voipms-00000925”, “GOSUB_RETVAL=”) in new stack
3051 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-blkvm-check:2] ExecIf(“SIP/voipms-00000925”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
3052 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-blkvm-check:3] MacroExit(“SIP/voipms-00000925”, “”) in new stack
3053 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-vm:4] GotoIf(“SIP/voipms-00000925”, “1?vmx,1”) in new stack
3054 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx_builtins.c: Goto (macro-vm,vmx,1)
3055 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:1] Set(“SIP/voipms-00000925”, “__EXTTOCALL=119”) in new stack
3056 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:2] Set(“SIP/voipms-00000925”, “__CRM_VOICEMAIL=119”) in new stack
3057 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:3] Set(“SIP/voipms-00000925”, “MEXTEN=119”) in new stack
3058 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:4] Set(“SIP/voipms-00000925”, “MMODE=NOANSWER”) in new stack
3059 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:5] Set(“SIP/voipms-00000925”, “RETVM=”) in new stack
3060 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:6] Set(“SIP/voipms-00000925”, “MODE=unavail”) in new stack
3061 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:7] Macro(“SIP/voipms-00000925”, “get-vmcontext,119”) in new stack
3062 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“SIP/voipms-00000925”, “VMCONTEXT=default”) in new stack
3063 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/voipms-00000925”, “0?200:300”) in new stack
3064 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
3065 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“SIP/voipms-00000925”, “”) in new stack
3066 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:8] Set(“SIP/voipms-00000925”, “MODE=unavail”) in new stack
3067 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:9] NoOp(“SIP/voipms-00000925”, “MODE IS: unavail”) in new stack
3068 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:10] GotoIf(“SIP/voipms-00000925”, “1?chknomsg”) in new stack
3069 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx_builtins.c: Goto (macro-vm,vmx,12)
3070 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:12] GotoIf(“SIP/voipms-00000925”, “0?s-NOANSWER,1”) in new stack
3071 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:13] GotoIf(“SIP/voipms-00000925”, “1?notdirect”) in new stack
3072 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx_builtins.c: Goto (macro-vm,vmx,15)
3073 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:15] NoOp(“SIP/voipms-00000925”, "Checking if ext 119 is enabled: ") in new stack
3074 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [vmx@macro-vm:16] GotoIf(“SIP/voipms-00000925”, “1?s-NOANSWER,1”) in new stack
3075 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx_builtins.c: Goto (macro-vm,s-NOANSWER,1)
3076 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s-NOANSWER@macro-vm:1] Macro(“SIP/voipms-00000925”, “get-vmcontext,119”) in new stack
3077 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-get-vmcontext:1] Set(“SIP/voipms-00000925”, “VMCONTEXT=default”) in new stack
3078 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/voipms-00000925”, “0?200:300”) in new stack
3079 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
3080 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-get-vmcontext:300] NoOp(“SIP/voipms-00000925”, “”) in new stack
3081 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] pbx.c: Executing [s-NOANSWER@macro-vm:2] VoiceMail(“SIP/voipms-00000925”, “119@default,u”) in new stack
3082 [2022-05-27 09:58:20] VERBOSE[2796][C-00000537] file.c: <SIP/voipms-00000925> Playing ‘/var/spool/asterisk/voicemail/default/119/unavail.slin’ (language ‘en’)
3083 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] app_macro.c: Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘SIP/voipms-00000925’ in macro ‘vm’
3084 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Spawn extension (ext-local, 119, 5) exited non-zero on ‘SIP/voipms-00000925’
3085 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [h@ext-local:1] Macro(“SIP/voipms-00000925”, “hangupcall,”) in new stack
3086 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/voipms-00000925”, “1?theend”) in new stack
3087 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx_builtins.c: Goto (macro-hangupcall,s,3)
3088 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/voipms-00000925”, “0?Set(CDR(recordingfile)=)”) in new stack
3089 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/voipms-00000925”, “”) in new stack
3090 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/voipms-00000925’ in macro ‘hangupcall’
3091 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/voipms-00000925’
3092 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] app_stack.c: SIP/voipms-00000925 Internal Gosub(crm-hangup,s,1) start
3093 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [s@crm-hangup:1] NoOp(“SIP/voipms-00000925”, “Sending Hangup to CRM”) in new stack
3094 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [s@crm-hangup:2] NoOp(“SIP/voipms-00000925”, “HANGUP CAUSE: 16”) in new stack
3095 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [s@crm-hangup:3] ExecIf(“SIP/voipms-00000925”, “1?Set(__CRM_VOICEMAIL=FAILED)”) in new stack
3096 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [s@crm-hangup:4] NoOp(“SIP/voipms-00000925”, “MASTER CHANNEL: 1653667064.18005 = 1653667064.18005”) in new stack
3097 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [s@crm-hangup:5] GotoIf(“SIP/voipms-00000925”, “0?return”) in new stack
3098 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [s@crm-hangup:6] Set(“SIP/voipms-00000925”, “__CRM_HANGUP=1”) in new stack
3099 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [s@crm-hangup:7] AGI(“SIP/voipms-00000925”, “agi://127.0.0.1/sangomacrm.agi”) in new stack
3100 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] res_agi.c: <SIP/voipms-00000925>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
3101 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] pbx.c: Executing [s@crm-hangup:8] Return(“SIP/voipms-00000925”, “”) in new stack
3102 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/voipms-00000925’
3103 [2022-05-27 09:58:24] VERBOSE[2796][C-00000537] app_stack.c: SIP/voipms-00000925 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=