Inbound route - Voicemail working for a DID but not for another DID

Hi all,

This is my first post on this Wiki (which helped me a lot for other common problems).
I now have a strange issue, and I can’t find the solution.
For one of our customer, we installed a FreePBX with framework version 14.0.3.6.
He’s got 2 differents DID and for this, I created 2 differents inbound routes (I created 2 “Time conditions” depending on the number called…)

So, here is the call flow:

  1. 1st DID called --> Inbound route for 1st DID with a Time Condition as Destination
    –> Matched : go to Ring group 301 (with extensions 201 & 202) and if no answer --> go to extension 203 --> if no answer then go to Voicemail of this extension.

This is working perfectly for the 1st DID.

  1. 2nd DID called --> Inbound route for 2nd DID with a Time Condition as Destination
    –> Matched : go to Ring group 301 (with same extensions as above : 201 & 202) and if no answer --> go to extension 203 --> if no answer then go to Voicemail of this extension.

I don’t know why, but with the second DID, the call just hang up after around 30 seconds on the last extension 203, without playing the voicemail message…

Remark:
There is also another DID assigned to the extension 203 (last extension with a private number), with another inbound route (call flow : Other DID Called --> Extension 203 --> Voicemail if no answer).
This one is also working perfectly, I’m reaching the extension voicemail if I call this particular DID.

So, I compare 2 calls from the 2 scenario explained above, and here are the logs I could retrieve.
I display only the end of the call flow below -

  1. the working one -


[2018-07-03 12:17:01] VERBOSE[27313][C-0000024c] app_dial.c: Called SIP/203
[2018-07-03 12:17:01] VERBOSE[27313][C-0000024c] app_dial.c: Connected line update to SIP/3starsnet-00000510 prevented.
[2018-07-03 12:17:01] VERBOSE[11314] chan_sip.c: Extension Changed 203[ext-local] new state Ringing for Notify User 201
[2018-07-03 12:17:01] VERBOSE[11314] chan_sip.c: Extension Changed 203[ext-local] new state Ringing for Notify User 202
[2018-07-03 12:17:01] VERBOSE[27313][C-0000024c] app_dial.c: SIP/203-00000513 is ringing
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] app_dial.c: Nobody picked up in 30000 ms
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] app_stack.c: SIP/203-00000513 Internal Gosub(crm-hangup,s,1) start
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] NoOp(“SIP/203-00000513”, “Sending Hangup to CRM”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:2] NoOp(“SIP/203-00000513”, “HANGUP CAUSE: 16”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/203-00000513”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:4] NoOp(“SIP/203-00000513”, “MASTER CHANNEL: 1530613021.1353 = 1530612991.1350”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:5] GotoIf(“SIP/203-00000513”, “1?return”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (crm-hangup,s,8)
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:8] Return(“SIP/203-00000513”, “”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] app_stack.c: Spawn extension (from-internal, 203, 1) exited non-zero on ‘SIP/203-00000513’
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] app_stack.c: SIP/203-00000513 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:56] ExecIf(“SIP/3starsnet-00000510”, “0?MacroExit()”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:57] ExecIf(“SIP/3starsnet-00000510”, “0?Set(DIALSTATUS=)”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:58] GosubIf(“SIP/3starsnet-00000510”, “0?s-NOANSWER,1()”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:59] MacroExit(“SIP/3starsnet-00000510”, “”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:27] Set(“SIP/3starsnet-00000510”, “SV_DIALSTATUS=NOANSWER”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:28] GosubIf(“SIP/3starsnet-00000510”, “0?docfu,1()”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:29] GosubIf(“SIP/3starsnet-00000510”, “0?docfb,1()”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:30] Set(“SIP/3starsnet-00000510”, “DIALSTATUS=NOANSWER”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:31] ExecIf(“SIP/3starsnet-00000510”, “1?MacroExit()”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:3] Set(“SIP/3starsnet-00000510”, “__PICKUPMARK=”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:4] GotoIf(“SIP/3starsnet-00000510”, “1?ext-local,vmu203,1”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (ext-local,vmu203,1)
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] Macro(“SIP/3starsnet-00000510”, “vm,203,NOANSWER,”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] Macro(“SIP/3starsnet-00000510”, “user-callerid,SKIPTTL”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] Set(“SIP/3starsnet-00000510”, “TOUCH_MONITOR=1530612991.1350”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:2] Set(“SIP/3starsnet-00000510”, “AMPUSER=0485830996”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:3] GotoIf(“SIP/3starsnet-00000510”, “0?report”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:4] ExecIf(“SIP/3starsnet-00000510”, “0?Set(REALCALLERIDNUM=)") in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:5] Set(“SIP/3starsnet-00000510”, “AMPUSER=”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:6] GotoIf(“SIP/3starsnet-00000510”, “0?limit”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:7] Set(“SIP/3starsnet-00000510”, “AMPUSERCIDNAME=”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:8] ExecIf(“SIP/3starsnet-00000510”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:9] GotoIf(“SIP/3starsnet-00000510”, “1?report”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:16] NoOp(“SIP/3starsnet-00000510”, “Macro Depth is 2”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:17] GotoIf(“SIP/3starsnet-00000510”, “1?report2:macroerror”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:18] GotoIf(“SIP/3starsnet-00000510”, “1?continue”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:37] Set(“SIP/3starsnet-00000510”, "CALLERID(number)=
”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:38] Set(“SIP/3starsnet-00000510”, “CALLERID(name)=Manneh:") in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:39] GotoIf(“SIP/3starsnet-00000510”, “0?cnum”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:40] Set(“SIP/3starsnet-00000510”, "CDR(cnam)=Manneh:
”) in new stack
[2018-07-03 12:17:31] VERBOSE[11314] chan_sip.c: Extension Changed 203[ext-local] new state Idle for Notify User 201
[2018-07-03 12:17:31] VERBOSE[11314] chan_sip.c: Extension Changed 203[ext-local] new state Idle for Notify User 202
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:41] Set(“SIP/3starsnet-00000510”, “CDR(cnum)=**********”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:42] Set(“SIP/3starsnet-00000510”, “CHANNEL(language)=fr”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:2] Set(“SIP/3starsnet-00000510”, “VMGAIN=”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:3] Macro(“SIP/3starsnet-00000510”, “blkvm-check,”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] Set(“SIP/3starsnet-00000510”, “GOSUB_RETVAL=”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:2] ExecIf(“SIP/3starsnet-00000510”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:3] MacroExit(“SIP/3starsnet-00000510”, “”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:4] GotoIf(“SIP/3starsnet-00000510”, “1?vmx,1”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (macro-vm,vmx,1)
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] Set(“SIP/3starsnet-00000510”, “__EXTTOCALL=203”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:2] Set(“SIP/3starsnet-00000510”, “__CRM_VOICEMAIL=203”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:3] Set(“SIP/3starsnet-00000510”, “MEXTEN=203”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:4] Set(“SIP/3starsnet-00000510”, “MMODE=NOANSWER”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:5] Set(“SIP/3starsnet-00000510”, “RETVM=”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:6] Set(“SIP/3starsnet-00000510”, “MODE=unavail”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:7] Macro(“SIP/3starsnet-00000510”, “get-vmcontext,203”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] Set(“SIP/3starsnet-00000510”, “VMCONTEXT=default”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:2] GotoIf(“SIP/3starsnet-00000510”, “0?200:300”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:300] NoOp(“SIP/3starsnet-00000510”, “”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:8] Set(“SIP/3starsnet-00000510”, “MODE=unavail”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:9] NoOp(“SIP/3starsnet-00000510”, “MODE IS: unavail”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:10] GotoIf(“SIP/3starsnet-00000510”, “1?chknomsg”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (macro-vm,vmx,12)
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:12] GotoIf(“SIP/3starsnet-00000510”, “0?s-NOANSWER,1”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:13] GotoIf(“SIP/3starsnet-00000510”, “1?notdirect”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (macro-vm,vmx,15)
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:15] NoOp(“SIP/3starsnet-00000510”, "Checking if ext 203 is enabled: ") in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:16] GotoIf(“SIP/3starsnet-00000510”, “1?s-NOANSWER,1”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (macro-vm,s-NOANSWER,1)
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] Macro(“SIP/3starsnet-00000510”, “get-vmcontext,203”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] Set(“SIP/3starsnet-00000510”, “VMCONTEXT=default”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:2] GotoIf(“SIP/3starsnet-00000510”, “0?200:300”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (macro-get-vmcontext,s,300)
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:300] NoOp(“SIP/3starsnet-00000510”, “”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:2] VoiceMail(“SIP/3starsnet-00000510”, “[email protected],u”) in new stack
[2018-07-03 12:17:31] VERBOSE[27313][C-0000024c] file.c: <SIP/3starsnet-00000510> Playing ‘/var/spool/asterisk/voicemail/default/203/unavail.slin’ (language ‘fr’)
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] app_macro.c: Spawn extension (macro-vm, s-NOANSWER, 2) exited non-zero on ‘SIP/3starsnet-00000510’ in macro ‘vm’
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Spawn extension (ext-local, vmu203, 1) exited non-zero on ‘SIP/3starsnet-00000510’
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] Macro(“SIP/3starsnet-00000510”, “hangupcall,”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] GotoIf(“SIP/3starsnet-00000510”, “1?theend”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/3starsnet-00000510”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:4] NoOp(“SIP/3starsnet-00000510”, " monior file= ") in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:5] AGI(“SIP/3starsnet-00000510”, “attendedtransfer-rec-restart.php,”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] res_agi.c: <SIP/3starsnet-00000510>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:6] Hangup(“SIP/3starsnet-00000510”, “”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘SIP/3starsnet-00000510’ in macro ‘hangupcall’
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/3starsnet-00000510’
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] app_stack.c: SIP/3starsnet-00000510 Internal Gosub(crm-hangup,s,1) start
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:1] NoOp(“SIP/3starsnet-00000510”, “Sending Hangup to CRM”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:2] NoOp(“SIP/3starsnet-00000510”, “HANGUP CAUSE: 16”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/3starsnet-00000510”, “1?Set(__CRM_VOICEMAIL=FAILED)”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:4] NoOp(“SIP/3starsnet-00000510”, “MASTER CHANNEL: 1530612991.1350 = 1530612991.1350”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:5] GotoIf(“SIP/3starsnet-00000510”, “0?return”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:6] Set(“SIP/3starsnet-00000510”, “__CRM_HANGUP=1”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:7] AGI(“SIP/3starsnet-00000510”, “sangomacrm.agi”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] res_agi.c: <SIP/3starsnet-00000510>AGI Script sangomacrm.agi completed, returning 0
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] pbx.c: Executing [[email protected]:8] Return(“SIP/3starsnet-00000510”, “”) in new stack
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/3starsnet-00000510’
[2018-07-03 12:17:41] VERBOSE[27313][C-0000024c] app_stack.c: SIP/3starsnet-00000510 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=


[2018-07-03 12:18:40] VERBOSE[27628][C-0000024d] app_dial.c: Called SIP/203
[2018-07-03 12:18:40] VERBOSE[27628][C-0000024d] app_dial.c: Connected line update to SIP/3starsnet-00000514 prevented.
[2018-07-03 12:18:40] VERBOSE[11314] chan_sip.c: Extension Changed 203[ext-local] new state Ringing for Notify User 201
[2018-07-03 12:18:40] VERBOSE[11314] chan_sip.c: Extension Changed 203[ext-local] new state Ringing for Notify User 202
[2018-07-03 12:18:40] VERBOSE[27628][C-0000024d] app_dial.c: SIP/203-00000517 is ringing
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] app_stack.c: SIP/203-00000517 Internal Gosub(crm-hangup,s,1) start
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:1] NoOp(“SIP/203-00000517”, “Sending Hangup to CRM”) in new stack
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:2] NoOp(“SIP/203-00000517”, “HANGUP CAUSE: 16”) in new stack
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/203-00000517”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:4] NoOp(“SIP/203-00000517”, “MASTER CHANNEL: 1530613120.1357 = 1530613089.1354”) in new stack
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:5] GotoIf(“SIP/203-00000517”, “1?return”) in new stack
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx_builtins.c: Goto (crm-hangup,s,8)
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:8] Return(“SIP/203-00000517”, “”) in new stack
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] app_stack.c: Spawn extension (from-internal, 203, 1) exited non-zero on ‘SIP/203-00000517’
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] app_stack.c: SIP/203-00000517 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] app_macro.c: Spawn extension (macro-dial-one, s, 55) exited non-zero on ‘SIP/3starsnet-00000514’ in macro ‘dial-one’
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] app_macro.c: Spawn extension (macro-exten-vm, s, 26) exited non-zero on ‘SIP/3starsnet-00000514’ in macro ‘exten-vm’
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Spawn extension (ext-local, 203, 2) exited non-zero on ‘SIP/3starsnet-00000514’
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:1] Macro(“SIP/3starsnet-00000514”, “hangupcall,”) in new stack
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:1] GotoIf(“SIP/3starsnet-00000514”, “1?theend”) in new stack
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-07-03 12:19:08] VERBOSE[11314] chan_sip.c: Extension Changed 203[ext-local] new state Idle for Notify User 201
[2018-07-03 12:19:08] VERBOSE[11314] chan_sip.c: Extension Changed 203[ext-local] new state Idle for Notify User 202
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/3starsnet-00000514”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:4] NoOp(“SIP/3starsnet-00000514”, "SIP/203-00000517 monior file= ") in new stack
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:5] AGI(“SIP/3starsnet-00000514”, “attendedtransfer-rec-restart.php,SIP/203-00000517,”) in new stack
[2018-07-03 12:19:08] VERBOSE[27628][C-0000024d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] res_agi.c: <SIP/3starsnet-00000514>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:6] Hangup(“SIP/3starsnet-00000514”, “”) in new stack
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘SIP/3starsnet-00000514’ in macro ‘hangupcall’
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/3starsnet-00000514’
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] app_stack.c: SIP/3starsnet-00000514 Internal Gosub(crm-hangup,s,1) start
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:1] NoOp(“SIP/3starsnet-00000514”, “Sending Hangup to CRM”) in new stack
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:2] NoOp(“SIP/3starsnet-00000514”, “HANGUP CAUSE: 16”) in new stack
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:3] ExecIf(“SIP/3starsnet-00000514”, “0?Set(__CRM_VOICEMAIL=)”) in new stack
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:4] NoOp(“SIP/3starsnet-00000514”, “MASTER CHANNEL: 1530613089.1354 = 1530613089.1354”) in new stack
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:5] GotoIf(“SIP/3starsnet-00000514”, “0?return”) in new stack
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:6] Set(“SIP/3starsnet-00000514”, “__CRM_HANGUP=1”) in new stack
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:7] AGI(“SIP/3starsnet-00000514”, “sangomacrm.agi”) in new stack
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] res_agi.c: <SIP/3starsnet-00000514>AGI Script sangomacrm.agi completed, returning 0
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] pbx.c: Executing [[email protected]:8] Return(“SIP/3starsnet-00000514”, “”) in new stack
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] app_stack.c: Spawn extension (ext-local, h, 1) exited non-zero on ‘SIP/3starsnet-00000514’
[2018-07-03 12:19:09] VERBOSE[27628][C-0000024d] app_stack.c: SIP/3starsnet-00000514 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=

Any help about this would be really appreciated.
Have a nice day all !!

Guillaume.

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