FreePBX | Register | Issues | Wiki | Portal | Support

Ring group spiteful, the last extension indicated does not ring ... maybe a bug?

bug
Tags: #<Tag:0x00007fcd1fd4b770>

(Claudio Pelosi) #1

I have a freePBX that makes me a strange thing …
I add extensions in the group, the last extension indicated does not ring
FreePBX 13
Current Asterisk Version: 13.17.1
Ring Groups 13.0.23.2 StableSangoma

What do you think?


(Itzik) #2

Obvious question. When you call that extension direct, does it ring?
What ring strategy do you have?
A call trace should tell you why it’s not ringing/calling. https://wiki.freepbx.org/display/SUP/Providing+Great+Debug#ProvidingGreatDebug-AsteriskLogs


(Claudio Pelosi) #3

the ring strategy is obviously about everyone
yes, the extension obviously quilted normally
the strangeness is: it does not only play the last of the list
for example if I put the inside 10 as the last one will not ring the 10 if I put the 12 will not ring the 12
The last is always the one that does not ring whatever it is
I was clear I hope!

I attach image…:


(Itzik) #4
  1. Did you take a look at the logs?
  2. If you set skip busy to no, is the issue still happening?
  3. Did you try re-creating the ring group?
  4. Did you try with 3 digit extensions?
  5. Did you try upgrading Asterisk?

(Claudio Pelosi) #5

Asterisk is updated
I tried to create a new group but nothing remains the problem


(Claudio Pelosi) #6

[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [0192940263@from-pstn-toheader:1] NoOp(“SIP/0192940263_in-0000029c”, “Attempting to extract DID from SIP To header”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [0192940263@from-pstn-toheader:2] GotoIf(“SIP/0192940263_in-0000029c”, “1?SIP”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (from-pstn-toheader,0192940263,6)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [0192940263@from-pstn-toheader:6] Goto(“SIP/0192940263_in-0000029c”, “from-pstn,019677171,1”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (from-pstn,019677171,1)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:1] Set(“SIP/0192940263_in-0000029c”, “__DIRECTION=INBOUND”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:2] Gosub(“SIP/0192940263_in-0000029c”, “sub-record-check,s,1(in,019677171,yes)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/0192940263_in-0000029c”, “0?initialized”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:2] Set(“SIP/0192940263_in-0000029c”, “__REC_STATUS=INITIALIZED”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:3] Set(“SIP/0192940263_in-0000029c”, “NOW=1544105056”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:4] Set(“SIP/0192940263_in-0000029c”, “__DAY=06”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:5] Set(“SIP/0192940263_in-0000029c”, “__MONTH=12”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:6] Set(“SIP/0192940263_in-0000029c”, “__YEAR=2018”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:7] Set(“SIP/0192940263_in-0000029c”, “__TIMESTR=20181206-150416”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:8] Set(“SIP/0192940263_in-0000029c”, “__FROMEXTEN=unknown”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:9] Set(“SIP/0192940263_in-0000029c”, “__MON_FMT=wav”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/0192940263_in-0000029c”, “Recordings initialized”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(ARG3=dontcare)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/0192940263_in-0000029c”, “REC_POLICY_MODE_SAVE=”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/0192940263_in-0000029c”, “2?checkaction”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/0192940263_in-0000029c”, “1?sub-record-check,in,1”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (sub-record-check,in,1)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [in@sub-record-check:1] NoOp(“SIP/0192940263_in-0000029c”, “Inbound Recording Check to 019677171”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [in@sub-record-check:2] Set(“SIP/0192940263_in-0000029c”, “FROMEXTEN=unknown”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [in@sub-record-check:3] ExecIf(“SIP/0192940263_in-0000029c”, “10?Set(FROMEXTEN=0182586863)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [in@sub-record-check:4] Gosub(“SIP/0192940263_in-0000029c”, “recordcheck,1(yes,in,019677171)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/0192940263_in-0000029c”, “Starting recording check against yes”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/0192940263_in-0000029c”, “yes”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (sub-record-check,recordcheck,9)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf(“SIP/0192940263_in-0000029c”, “0?Return()”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:10] Set(“SIP/0192940263_in-0000029c”, “__REC_POLICY_MODE=YES”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:11] Goto(“SIP/0192940263_in-0000029c”, “startrec”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (sub-record-check,recordcheck,16)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp(“SIP/0192940263_in-0000029c”, “Starting recording: in, 019677171”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:17] Set(“SIP/0192940263_in-0000029c”, “AUDIOHOOK_INHERIT(MixMonitor)=yes”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:18] Set(“SIP/0192940263_in-0000029c”, “__CALLFILENAME=in-019677171-0182586863-20181206-150416-1544105056.668”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:19] MixMonitor(“SIP/0192940263_in-0000029c”, “2018/12/06/in-019677171-0182586863-20181206-150416-1544105056.668.wav,abi(LOCAL_MIXMON_ID),”) in new stack
[2018-12-06 15:04:16] VERBOSE[16998][C-0000008f] app_mixmonitor.c: Begin MixMonitor Recording SIP/0192940263_in-0000029c
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:20] Set(“SIP/0192940263_in-0000029c”, “__MIXMON_ID=0x703586e8”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:21] Set(“SIP/0192940263_in-0000029c”, “__RECORD_ID=SIP/0192940263_in-0000029c”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:22] Set(“SIP/0192940263_in-0000029c”, “__REC_STATUS=RECORDING”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:23] Set(“SIP/0192940263_in-0000029c”, “CDR(recordingfile)=in-019677171-0182586863-20181206-150416-1544105056.668.wav”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:24] Return(“SIP/0192940263_in-0000029c”, “”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [in@sub-record-check:5] Return(“SIP/0192940263_in-0000029c”, “”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:3] Set(“SIP/0192940263_in-0000029c”, “__FROM_DID=019677171”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:4] Set(“SIP/0192940263_in-0000029c”, “CDR(did)=019677171”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:5] ExecIf(“SIP/0192940263_in-0000029c”, “1 ?Set(CALLERID(name)=0182586863)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:6] Set(“SIP/0192940263_in-0000029c”, “__MOHCLASS=”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:7] Set(“SIP/0192940263_in-0000029c”, “__REVERSAL_REJECT=FALSE”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:8] GotoIf(“SIP/0192940263_in-0000029c”, “1?post-reverse-charge”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (from-pstn,019677171,10)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:10] NoOp(“SIP/0192940263_in-0000029c”, “”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:11] Set(“SIP/0192940263_in-0000029c”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:12] Set(“SIP/0192940263_in-0000029c”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:13] Set(“SIP/0192940263_in-0000029c”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:14] Set(“SIP/0192940263_in-0000029c”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:15] NoOp(“SIP/0192940263_in-0000029c”, “CallerID Entry Point”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [019677171@from-pstn:16] Goto(“SIP/0192940263_in-0000029c”, “timeconditions,2,1”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (timeconditions,2,1)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [2@timeconditions:1] Set(“SIP/0192940263_in-0000029c”, “DB(TC/2/INUSESTATE)=INUSE”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [2@timeconditions:2] Set(“SIP/0192940263_in-0000029c”, “DB(TC/2/NOT_INUSESTATE)=NOT_INUSE”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [2@timeconditions:3] NoOp(“SIP/0192940263_in-0000029c”, “TIMENOW: 15:04,Thu, 6,Dec,Europe/Rome”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [2@timeconditions:4] NoOp(“SIP/0192940263_in-0000029c”, “TIMEMATCHED: FALSE”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [2@timeconditions:5] GotoIfTime(“SIP/0192940263_in-0000029c”, “09:30-12:30,mon-fri,,,Europe/Rome?truestate”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [2@timeconditions:6] GotoIf(“SIP/0192940263_in-0000029c”, “0?truegoto”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [2@timeconditions:7] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(DB(TC/2)=)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [2@timeconditions:8] Set(“SIP/0192940263_in-0000029c”, “DEVICE_STATE(Custom:TC2)=INUSE”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [2@timeconditions:9] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(DEVICE_STATE(Custom:TCSTICKY)=INUSE)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [2@timeconditions:10] GotoIf(“SIP/0192940263_in-0000029c”, “1?ext-group,602,1”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (ext-group,602,1)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:1] GotoIf(“SIP/0192940263_in-0000029c”, “0?cid”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:2] Progress(“SIP/0192940263_in-0000029c”, “”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:3] Macro(“SIP/0192940263_in-0000029c”, “user-callerid,”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:1] Set(“SIP/0192940263_in-0000029c”, “TOUCH_MONITOR=1544105056.668”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:2] Set(“SIP/0192940263_in-0000029c”, “AMPUSER=0182586863”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:3] GotoIf(“SIP/0192940263_in-0000029c”, “0?report”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:4] ExecIf(“SIP/0192940263_in-0000029c”, “1?Set(REALCALLERIDNUM=0182586863)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:5] Set(“SIP/0192940263_in-0000029c”, “AMPUSER=”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:6] GotoIf(“SIP/0192940263_in-0000029c”, “0?limit”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:7] Set(“SIP/0192940263_in-0000029c”, “AMPUSERCIDNAME=”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:8] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:9] GotoIf(“SIP/0192940263_in-0000029c”, “1?report”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:16] NoOp(“SIP/0192940263_in-0000029c”, “Macro Depth is 1”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“SIP/0192940263_in-0000029c”, “1?report2:macroerror”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:18] GotoIf(“SIP/0192940263_in-0000029c”, “0?continue”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:19] Set(“SIP/0192940263_in-0000029c”, “__TTL=64”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:20] GotoIf(“SIP/0192940263_in-0000029c”, “1?continue”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (macro-user-callerid,s,36)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:36] Set(“SIP/0192940263_in-0000029c”, “CALLERID(number)=0182586863”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:37] Set(“SIP/0192940263_in-0000029c”, “CALLERID(name)=0182586863”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:38] GotoIf(“SIP/0192940263_in-0000029c”, “0?cnum”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:39] Set(“SIP/0192940263_in-0000029c”, “CDR(cnam)=0182586863”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:40] Set(“SIP/0192940263_in-0000029c”, “CDR(cnum)=0182586863”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-user-callerid:41] Set(“SIP/0192940263_in-0000029c”, “CHANNEL(language)=it”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:4] Macro(“SIP/0192940263_in-0000029c”, “blkvm-setifempty,”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-blkvm-setifempty:1] GotoIf(“SIP/0192940263_in-0000029c”, “1?init”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (macro-blkvm-setifempty,s,4)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-blkvm-setifempty:4] Set(“SIP/0192940263_in-0000029c”, “__BLKVM_CHANNEL=SIP/0192940263_in-0000029c”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-blkvm-setifempty:5] Set(“SIP/0192940263_in-0000029c”, “SHARED(BLKVM,SIP/0192940263_in-0000029c)=TRUE”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-blkvm-setifempty:6] Set(“SIP/0192940263_in-0000029c”, “GOSUB_RETVAL=TRUE”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-blkvm-setifempty:7] MacroExit(“SIP/0192940263_in-0000029c”, “”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:5] GotoIf(“SIP/0192940263_in-0000029c”, “1?skipov”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (ext-group,602,8)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:8] Set(“SIP/0192940263_in-0000029c”, “RRNODEST=”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:9] Set(“SIP/0192940263_in-0000029c”, “__NODEST=602”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:10] GosubIf(“SIP/0192940263_in-0000029c”, “0?sub-rgsetcid,s,1()”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:11] Set(“SIP/0192940263_in-0000029c”, “__PICKUPMARK=602”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:12] Gosub(“SIP/0192940263_in-0000029c”, “sub-record-check,s,1(rg,602,dontcare)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:1] GotoIf(“SIP/0192940263_in-0000029c”, “10?initialized”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (sub-record-check,s,10)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:10] NoOp(“SIP/0192940263_in-0000029c”, “Recordings initialized”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:11] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(ARG3=dontcare)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:12] Set(“SIP/0192940263_in-0000029c”, “REC_POLICY_MODE_SAVE=YES”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:13] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(REC_STATUS=NO)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:14] GotoIf(“SIP/0192940263_in-0000029c”, “2?checkaction”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (sub-record-check,s,17)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:17] GotoIf(“SIP/0192940263_in-0000029c”, “0?sub-record-check,rg,1”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:18] NoOp(“SIP/0192940263_in-0000029c”, “Generic rg Recording Check - 0182586863 602”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:19] Gosub(“SIP/0192940263_in-0000029c”, “recordcheck,1(dontcare,rg,602)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“SIP/0192940263_in-0000029c”, “Starting recording check against dontcare”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“SIP/0192940263_in-0000029c”, “dontcare”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“SIP/0192940263_in-0000029c”, “”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@sub-record-check:20] Return(“SIP/0192940263_in-0000029c”, “”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:13] Set(“SIP/0192940263_in-0000029c”, “RingGroupMethod=ringall”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [602@ext-group:14] Macro(“SIP/0192940263_in-0000029c”, “dial,40,m(msgbenvenuto)Tt,10-11-12”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:1] NoOp(“SIP/0192940263_in-0000029c”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:2] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(ALERT_INFO=)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:3] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(ALERT_INFO=)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:4] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(ALERT_INFO=)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:5] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(CHANNEL(musicclass)=)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:6] AGI(“SIP/0192940263_in-0000029c”, “dialparties.agi”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Caller ID name is ‘0182586863’ number is ‘0182586863’
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: CW Ignore is:
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: CF Ignore is:
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Added extension 10 to extension map
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Added extension 11 to extension map
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Added extension 12 to extension map
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Extension 10 cf is disabled
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Extension 11 cf is disabled
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Extension 12 cf is disabled
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Extension 10 do not disturb is disabled
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Extension 11 do not disturb is disabled


(Claudio Pelosi) #7

[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Extension 12 do not disturb is disabled
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Extension 10 has ExtensionState: 0
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 10
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: dbset CALLTRACE/10 to 0182586863
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Extension 11 has ExtensionState: 0
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 11
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: dbset CALLTRACE/11 to 0182586863
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Extension 12 has ExtensionState: 0
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Checking CW and CFB status for extension 12
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: dbset CALLTRACE/12 to 0182586863
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: dialparties.agi: Filtered ARG3: 10-11-12
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_agi.c: <SIP/0192940263_in-0000029c>AGI Script dialparties.agi completed, returning 0
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:9] NoOp(“SIP/0192940263_in-0000029c”, “Returned from dialparties with groups to dial”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:10] NoOp(“SIP/0192940263_in-0000029c”, "ringall array ") in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:11] Set(“SIP/0192940263_in-0000029c”, “__FMGL_DIAL=”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:12] Set(“SIP/0192940263_in-0000029c”, “LOOPCNT=3”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:13] Set(“SIP/0192940263_in-0000029c”, “ITER=1”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:14] Set(“SIP/0192940263_in-0000029c”, “__EXTTOCALL=10”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:15] NoOp(“SIP/0192940263_in-0000029c”, “Working with 10”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:16] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:17] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:18] Set(“SIP/0192940263_in-0000029c”, “ITER=2”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:19] GotoIf(“SIP/0192940263_in-0000029c”, “1?ndloopbegin”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (macro-dial,s,14)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:14] Set(“SIP/0192940263_in-0000029c”, “__EXTTOCALL=11”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:15] NoOp(“SIP/0192940263_in-0000029c”, “Working with 11”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:16] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:17] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:18] Set(“SIP/0192940263_in-0000029c”, “ITER=3”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:19] GotoIf(“SIP/0192940263_in-0000029c”, “1?ndloopbegin”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (macro-dial,s,14)
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:14] Set(“SIP/0192940263_in-0000029c”, “__EXTTOCALL=12”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:15] NoOp(“SIP/0192940263_in-0000029c”, “Working with 12”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:16] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:17] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:18] Set(“SIP/0192940263_in-0000029c”, “ITER=4”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:19] GotoIf(“SIP/0192940263_in-0000029c”, “0?ndloopbegin”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:20] Macro(“SIP/0192940263_in-0000029c”, “dial-ringall-predial-hook,”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit(“SIP/0192940263_in-0000029c”, “”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:21] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(ds=SIP/10&SIP/11&SIP/12,40,m(msgbenvenuto)tM(auto-blkvm)g)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-dial:22] Dial(“SIP/0192940263_in-0000029c”, “SIP/10&SIP/11&SIP/12,40,m(msgbenvenuto)tM(auto-blkvm)b(func-apply-sipheaders^s^1),”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] netsock2.c: Using SIP RTP TOS bits 184
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] netsock2.c: Using SIP RTP CoS mark 5
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] netsock2.c: Using SIP RTP TOS bits 184
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] netsock2.c: Using SIP RTP CoS mark 5
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] netsock2.c: Using SIP RTP TOS bits 184
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] netsock2.c: Using SIP RTP CoS mark 5
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_stack.c: SIP/10-0000029d Internal Gosub(func-apply-sipheaders,s,1) start
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“SIP/10-0000029d”, “Applying SIP Headers to channel”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“SIP/10-0000029d”, “SIPHEADERKEYS=”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:3] ExecIf(“SIP/10-0000029d”, “0?Set(Rheader=1)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:4] While(“SIP/10-0000029d”, “0”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_while.c: Jumping to priority 7
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“SIP/10-0000029d”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:9] Return(“SIP/10-0000029d”, “”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_stack.c: Spawn extension (from-internal, 602, 1) exited non-zero on ‘SIP/10-0000029d’
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_stack.c: SIP/10-0000029d Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_stack.c: SIP/11-0000029e Internal Gosub(func-apply-sipheaders,s,1) start
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“SIP/11-0000029e”, “Applying SIP Headers to channel”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“SIP/11-0000029e”, “SIPHEADERKEYS=”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:3] ExecIf(“SIP/11-0000029e”, “0?Set(Rheader=1)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:4] While(“SIP/11-0000029e”, “0”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_while.c: Jumping to priority 7
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“SIP/11-0000029e”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:9] Return(“SIP/11-0000029e”, “”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_stack.c: Spawn extension (from-internal, 602, 1) exited non-zero on ‘SIP/11-0000029e’
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_stack.c: SIP/11-0000029e Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_stack.c: SIP/12-0000029f Internal Gosub(func-apply-sipheaders,s,1) start
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp(“SIP/12-0000029f”, “Applying SIP Headers to channel”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:2] Set(“SIP/12-0000029f”, “SIPHEADERKEYS=”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:3] ExecIf(“SIP/12-0000029f”, “0?Set(Rheader=1)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:4] While(“SIP/12-0000029f”, “0”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_while.c: Jumping to priority 7
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:8] ExecIf(“SIP/12-0000029f”, “0?SIPRemoveHeader(Alert-Info:)”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@func-apply-sipheaders:9] Return(“SIP/12-0000029f”, “”) in new stack
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_stack.c: Spawn extension (from-internal, 602, 1) exited non-zero on ‘SIP/12-0000029f’
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_stack.c: SIP/12-0000029f Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_dial.c: Called SIP/10
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_dial.c: Called SIP/11
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_dial.c: Called SIP/12
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] res_musiconhold.c: Started music on hold, class ‘msgbenvenuto’, on channel ‘SIP/0192940263_in-0000029c’
[2018-12-06 15:04:16] WARNING[16997][C-0000008f] translate.c: no samples for alawtolin
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_dial.c: SIP/12-0000029f connected line has changed. Saving it until answer for SIP/0192940263_in-0000029c
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_dial.c: SIP/11-0000029e connected line has changed. Saving it until answer for SIP/0192940263_in-0000029c
[2018-12-06 15:04:16] VERBOSE[16997][C-0000008f] app_dial.c: SIP/10-0000029d connected line has changed. Saving it until answer for SIP/0192940263_in-0000029c
[2018-12-06 15:04:17] VERBOSE[1813][C-0000008f] chan_sip.c: Got SIP response 486 “Busy Here” back from 192.168.1.4:5070
[2018-12-06 15:04:17] VERBOSE[16997][C-0000008f] app_dial.c: SIP/12-0000029f is busy
[2018-12-06 15:04:17] VERBOSE[16997][C-0000008f] app_dial.c: SIP/10-0000029d is ringing
[2018-12-06 15:04:17] VERBOSE[16997][C-0000008f] app_dial.c: SIP/11-0000029e is ringing
[2018-12-06 15:04:19] VERBOSE[16997][C-0000008f] res_musiconhold.c: Stopped music on hold on SIP/0192940263_in-0000029c
[2018-12-06 15:04:19] VERBOSE[16997][C-0000008f] app_macro.c: Spawn extension (macro-dial, s, 22) exited non-zero on ‘SIP/0192940263_in-0000029c’ in macro ‘dial’
[2018-12-06 15:04:19] VERBOSE[16997][C-0000008f] pbx.c: Spawn extension (ext-group, 602, 14) exited non-zero on ‘SIP/0192940263_in-0000029c’
[2018-12-06 15:04:19] VERBOSE[16997][C-0000008f] pbx.c: Executing [h@ext-group:1] Macro(“SIP/0192940263_in-0000029c”, “hangupcall,”) in new stack
[2018-12-06 15:04:19] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“SIP/0192940263_in-0000029c”, “1?theend”) in new stack
[2018-12-06 15:04:19] VERBOSE[16997][C-0000008f] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2018-12-06 15:04:19] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-hangupcall:3] ExecIf(“SIP/0192940263_in-0000029c”, “0?Set(CDR(recordingfile)=)”) in new stack
[2018-12-06 15:04:19] VERBOSE[16997][C-0000008f] pbx.c: Executing [s@macro-hangupcall:4] Hangup(“SIP/0192940263_in-0000029c”, “”) in new stack
[2018-12-06 15:04:19] VERBOSE[16997][C-0000008f] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/0192940263_in-0000029c’ in macro ‘hangupcall’
[2018-12-06 15:04:19] VERBOSE[16997][C-0000008f] pbx.c: Spawn extension (ext-group, h, 1) exited non-zero on ‘SIP/0192940263_in-0000029c’

from the log everything seems ok …
at least for what I understand asterisk tries to make everyone ring


(Tom Ray) #8

All three extensions are in the Dial() string as they should be.

All three extensions are called, as they should be.

12 came back as busy but the other two rang.

So the issue now, is this Ring Group setup the same way as the other? Because the other had “Skip Busy Agents” but this one is clearly calling a busy agent. If these two Ring Groups are not setup the exact same way then your testing is skewed.


(Itzik) #9

No, it’s not. You said that:

That’s not the latest version of Asterisk available for FreePBX.


(Claudio Pelosi) #10

thanks, it’s true
painless procedure to update?


(Itzik) #11

yum update

Restart your PBX after updating


(Tom Ray) #12

Uhm, why? There is rarely anything that requires you to reboot after running “yum update”. That would mean doing “System Updates” in the GUI would require the PBX to reboot after they are done since the System Updates are the GUI way to execute “yum update”.

Linux is not Windows, it does not require a reboot with every update.


#13

If your kernel gets updated, you would definitely need to reboot, at least if you want to start using the new kernel.


(Itzik) #14

Plus, I’ve seen that after updating, FreePBX can sometimes display the wrong version until you restart asterisk.

So if you restart Asterisk, AND you anyway have some issues, you might as well want to do a safe reboot for the entire machine.


(Tom Ray) #15

Kernel updates qualify under the “rarely anything that requires”.


(Tom Ray) #16

Rebooting the server is not the same as start/stop/restarting a service. Again, *nix OSes have been this way for as long as I can remember. In very _limited_cases you need to reboot the entire server after you do updates. If you update Apache, PHP, MySQL or other “services” you have to restart them to use the new version. Asterisk is no different, when you update/recompile Asterisk is needs to be restarted.

In regards to the logic of “if you have issues, reboot” is the wrong logic path. If you’ve run an “yum update” and there are issues after that you should find out what those issues are and then fix them. Rarely will you need to reboot to fix them if you do it right. The whole “I reboot and it fixes the issue” doesn’t actually tell you why the issue happened or if it is something that could happen again. It is also the worst “troubleshooting” action out there.


#17

I might be wrong here, but IIRC dahdi updates and wanpipe updates need a reboot too.


(Tom Ray) #18

This is now just splitting hairs.


#19

I’m not trying to be a hair splitter, just mentioning that some updates do need reboots, but I also find it strange when a reboot seems the only way to fix a specific issue, or an issue suddenly goes away after reboot.


#20

I post with with a bit of bemusement, So such a process might need a reboot, , “Is that true”

The authoritarian says one thing,
The Theoretician says something else
The pragmatist says " WTF , reboot the F’er" it will take a minute or so and before i rebooted its still broke so seriously WTF??

Let’s try plan B if needed after the reboot . . . .