Ext. rings while dailed directly, but does not while in RingGroup

Hi Folks,

i have a funny thing here.

I have an extension (the client is a AVM FritzBox powering a Gigaset DECT Phone, its remote, via VPN, no NAT involved.)

I could call and recive calls to the extension from internal phones. All fine.

If I place it as a destination ext. 525 for an inbound route (straight to the extension) it works great.

But this ext. does not ring if I put it into a RingGroup. If I place it as only member of a RingGroup, the RingGroup goes stright to “Destination if no answer”

[2017-03-07 20:40:38] VERBOSE[3862][C-00000143] app_dial.c: Called PJSIP/525/sip:[email protected]:5060;uniq=4BC9F237DA6E7D3638FBE60AD85C2
[2017-03-07 20:40:38] VERBOSE[3862][C-00000143] app_dial.c: Connected line update to Local/525@from-internal-00000082;2 prevented.
[2017-03-07 20:40:38] VERBOSE[3857][C-00000143] app_dial.c: Local/525@from-internal-00000082;1 is ringing
[2017-03-07 20:40:38] VERBOSE[3862][C-00000143] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)

I think the last line says it, but its not congested… and while caling directly to 525 it works greate…

See:

[2017-03-07 20:48:22] VERBOSE[2966][C-00000144] netsock2.c: Using SIP RTP TOS bits 184
[2017-03-07 20:48:22] VERBOSE[2966][C-00000144] netsock2.c: Using SIP RTP CoS mark 5
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [525@from-internal:1] GotoIf("SIP/321-000001de", "1?ext-local,525,1:followme-check,525,1") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (ext-local,525,1)
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [525@ext-local:1] Set("SIP/321-000001de", "__RINGTIMER=15") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [525@ext-local:2] Macro("SIP/321-000001de", "exten-vm,novm,525,0,0,0") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-exten-vm:1] Macro("SIP/321-000001de", "user-callerid,") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/321-000001de", "TOUCH_MONITOR=1488916102.907") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/321-000001de", "AMPUSER=321") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/321-000001de", "0?report") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/321-000001de", "1?Set(__REALCALLERIDNUM=321)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/321-000001de", "AMPUSER=321") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/321-000001de", "0?limit") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/321-000001de", "AMPUSERCIDNAME=Manne-LoCom") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("SIP/321-000001de", "0?report") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:9] Set("SIP/321-000001de", "AMPUSERCID=32") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:10] Set("SIP/321-000001de", "__DIAL_OPTIONS=Ttr") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:11] Set("SIP/321-000001de", "CALLERID(all)="Manne-LoCom" <32>") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:12] GotoIf("SIP/321-000001de", "0?limit") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:13] ExecIf("SIP/321-000001de", "0?Set(GROUP(concurrency_limit)=321)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:14] ExecIf("SIP/321-000001de", "0?Set(CHANNEL(language)=)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:15] GotoIf("SIP/321-000001de", "0?continue") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:16] ExecIf("SIP/321-000001de", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:17] Set("SIP/321-000001de", "__TTL=64") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("SIP/321-000001de", "1?continue") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (macro-user-callerid,s,29)
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:29] Set("SIP/321-000001de", "CALLERID(number)=32") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:30] Set("SIP/321-000001de", "CALLERID(name)=Manne-LoCom") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:31] GotoIf("SIP/321-000001de", "0?cnum") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:32] Set("SIP/321-000001de", "CDR(cnam)=Manne-LoCom") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:33] Set("SIP/321-000001de", "CDR(cnum)=32") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-user-callerid:34] Set("SIP/321-000001de", "CHANNEL(language)=en") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-exten-vm:2] Set("SIP/321-000001de", "RingGroupMethod=none") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-exten-vm:3] Set("SIP/321-000001de", "__EXTTOCALL=525") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-exten-vm:4] Set("SIP/321-000001de", "__PICKUPMARK=525") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-exten-vm:5] Set("SIP/321-000001de", "RT=") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-exten-vm:6] ExecIf("SIP/321-000001de", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-exten-vm:7] ExecIf("SIP/321-000001de", "0?MacroExit()") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-exten-vm:8] Gosub("SIP/321-000001de", "sub-record-check,s,1(exten,525,dontcare)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/321-000001de", "0?initialized") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:2] Set("SIP/321-000001de", "__REC_STATUS=INITIALIZED") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:3] Set("SIP/321-000001de", "NOW=1488916102") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:4] Set("SIP/321-000001de", "__DAY=07") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:5] Set("SIP/321-000001de", "__MONTH=03") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:6] Set("SIP/321-000001de", "__YEAR=2017") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:7] Set("SIP/321-000001de", "__TIMESTR=20170307-204822") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:8] Set("SIP/321-000001de", "__FROMEXTEN=321") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:9] Set("SIP/321-000001de", "__MON_FMT=wav") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/321-000001de", "Recordings initialized") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/321-000001de", "0?Set(ARG3=dontcare)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:12] Set("SIP/321-000001de", "REC_POLICY_MODE_SAVE=") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/321-000001de", "0?Set(REC_STATUS=NO)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/321-000001de", "5?checkaction") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (sub-record-check,s,17)
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/321-000001de", "1?sub-record-check,exten,1") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (sub-record-check,exten,1)
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:1] NoOp("SIP/321-000001de", "Exten Recording Check between 321 and 525") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:2] Set("SIP/321-000001de", "CALLTYPE=internal") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:3] ExecIf("SIP/321-000001de", "0?Set(CALLTYPE=)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:4] Set("SIP/321-000001de", "CALLEE=dontcare") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:5] ExecIf("SIP/321-000001de", "0?Set(CALLEE=dontcare)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:6] GotoIf("SIP/321-000001de", "0?callee") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:7] GotoIf("SIP/321-000001de", "1?caller") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (sub-record-check,exten,13)
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:13] Set("SIP/321-000001de", "RECMODE=dontcare") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:14] ExecIf("SIP/321-000001de", "0?Set(RECMODE=dontcare)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:15] ExecIf("SIP/321-000001de", "1?Set(RECMODE=dontcare)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:16] Gosub("SIP/321-000001de", "recordcheck,1(dontcare,internal,525)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/321-000001de", "Starting recording check against dontcare") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/321-000001de", "dontcare") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [recordcheck@sub-record-check:3] Return("SIP/321-000001de", "") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [exten@sub-record-check:17] Return("SIP/321-000001de", "") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-exten-vm:9] GotoIf("SIP/321-000001de", "1?macrodial") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (macro-exten-vm,s,15)
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-exten-vm:15] GosubIf("SIP/321-000001de", "0?clrheader,1()") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-exten-vm:16] Macro("SIP/321-000001de", "dial-one,,Ttr,525") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:1] Set("SIP/321-000001de", "DEXTEN=525") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:2] ExecIf("SIP/321-000001de", "0?Set(EXTTOCALL=525)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:3] Set("SIP/321-000001de", "DIALSTATUS_CW=") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:4] GosubIf("SIP/321-000001de", "0?screen,1()") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:5] GosubIf("SIP/321-000001de", "0?cf,1()") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:6] GotoIf("SIP/321-000001de", "1?skip1") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (macro-dial-one,s,9)
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:9] GotoIf("SIP/321-000001de", "0?nodial") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:10] GotoIf("SIP/321-000001de", "0?continue") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:11] Set("SIP/321-000001de", "EXTHASCW=ENABLED") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:12] GotoIf("SIP/321-000001de", "0?next1:cwinusebusy") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (macro-dial-one,s,24)
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:24] GotoIf("SIP/321-000001de", "0?next3:continue") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (macro-dial-one,s,26)
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:26] GotoIf("SIP/321-000001de", "0?nodial") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:27] GosubIf("SIP/321-000001de", "1?dstring,1():dlocal,1()") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:1] Set("SIP/321-000001de", "DSTRING=") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:2] Set("SIP/321-000001de", "DEVICES=525") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:3] ExecIf("SIP/321-000001de", "0?Return()") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:4] ExecIf("SIP/321-000001de", "0?Set(DEVICES=25)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:5] Set("SIP/321-000001de", "LOOPCNT=1") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:6] Set("SIP/321-000001de", "ITER=1") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:7] Set("SIP/321-000001de", "THISDIAL=PJSIP/525") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:8] GosubIf("SIP/321-000001de", "1?zap2dahdi,1()") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/321-000001de", "0?Return()") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [zap2dahdi@macro-dial-one:2] Set("SIP/321-000001de", "NEWDIAL=") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [zap2dahdi@macro-dial-one:3] Set("SIP/321-000001de", "LOOPCNT2=1") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [zap2dahdi@macro-dial-one:4] Set("SIP/321-000001de", "ITER2=1") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [zap2dahdi@macro-dial-one:5] Set("SIP/321-000001de", "THISPART2=PJSIP/525") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/321-000001de", "0?Set(THISPART2=DAHDIIP/525)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [zap2dahdi@macro-dial-one:7] Set("SIP/321-000001de", "NEWDIAL=PJSIP/525&") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [zap2dahdi@macro-dial-one:8] Set("SIP/321-000001de", "ITER2=2") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/321-000001de", "0?begin2") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [zap2dahdi@macro-dial-one:10] Set("SIP/321-000001de", "THISDIAL=PJSIP/525") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [zap2dahdi@macro-dial-one:11] Return("SIP/321-000001de", "") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:9] GotoIf("SIP/321-000001de", "0?docheck") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:10] NoOp("SIP/321-000001de", "Debug: Found PJSIP Destination PJSIP/525") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:11] GotoIf("SIP/321-000001de", "0?doset") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:12] NoOp("SIP/321-000001de", "Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:13] Set("SIP/321-000001de", "THISDIAL=PJSIP/525/sip:[email protected]:5060;uniq=4BC9F237DA6E7D3638FBE60AD85C2") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:14] GotoIf("SIP/321-000001de", "0?skipset") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:15] Set("SIP/321-000001de", "DSTRING=PJSIP/525/sip:[email protected]:5060;uniq=4BC9F237DA6E7D3638FBE60AD85C2&") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:16] Set("SIP/321-000001de", "ITER=2") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:17] GotoIf("SIP/321-000001de", "0?begin") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:18] ExecIf("SIP/321-000001de", "0?Return()") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:19] Set("SIP/321-000001de", "DSTRING=PJSIP/525/sip:[email protected]:5060;uniq=4BC9F237DA6E7D3638FBE60AD85C2") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [dstring@macro-dial-one:20] Return("SIP/321-000001de", "") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:28] GotoIf("SIP/321-000001de", "0?nodial") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:29] GotoIf("SIP/321-000001de", "0?skiptrace") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:30] GosubIf("SIP/321-000001de", "1?ctset,1():ctclear,1()") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [ctset@macro-dial-one:1] Set("SIP/321-000001de", "DB(CALLTRACE/525)=32") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [ctset@macro-dial-one:2] Return("SIP/321-000001de", "") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:31] Set("SIP/321-000001de", "D_OPTIONS=Ttr") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:32] NoOp("SIP/321-000001de", "Blind Transfer: , Attended Transfer: , User: 321, Alert Info: ") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:33] ExecIf("SIP/321-000001de", "1?Set(ALERT_INFO=)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:34] ExecIf("SIP/321-000001de", "0?Set(ALERT_INFO=)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:35] ExecIf("SIP/321-000001de", "0?Set(ALERT_INFO=)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:36] ExecIf("SIP/321-000001de", "0?Set(ALERT_INFO=;volume=)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:37] ExecIf("SIP/321-000001de", "0?Set(ALERT_INFO=;volume=)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:38] GosubIf("SIP/321-000001de", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:39] ExecIf("SIP/321-000001de", "0?Set(CHANNEL(musicclass)=)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:40] GosubIf("SIP/321-000001de", "0?qwait,1()") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:41] Set("SIP/321-000001de", "__CWIGNORE=") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:42] Set("SIP/321-000001de", "__KEEPCID=TRUE") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:43] GotoIf("SIP/321-000001de", "0?usegoto,1") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:44] GotoIf("SIP/321-000001de", "0?godial") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:45] Gosub("SIP/321-000001de", "sub-presencestate-display,s,1(525)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@sub-presencestate-display:1] Goto("SIP/321-000001de", "state-not_set,1") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (sub-presencestate-display,state-not_set,1)
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [state-not_set@sub-presencestate-display:1] Set("SIP/321-000001de", "PRESENCESTATE_DISPLAY=") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [state-not_set@sub-presencestate-display:2] Return("SIP/321-000001de", "") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:46] Set("SIP/321-000001de", "CONNECTEDLINE(name,i)=Manne-Trumic") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:47] Set("SIP/321-000001de", "CONNECTEDLINE(num)=525") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:48] Set("SIP/321-000001de", "D_OPTIONS=TtrI") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:49] Macro("SIP/321-000001de", "dialout-one-predial-hook,") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/321-000001de", "") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:50] ExecIf("SIP/321-000001de", "0?Set(D_OPTIONS=trII)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-dial-one:51] Dial("SIP/321-000001de", "PJSIP/525/sip:[email protected]:5060;uniq=4BC9F237DA6E7D3638FBE60AD85C2,,TtrIb(func-apply-sipheaders^s^1)") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] app_stack.c: PJSIP/525-000000a1 Internal Gosub(func-apply-sipheaders,s,1) start
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("PJSIP/525-000000a1", "Applying SIP Headers to channel") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@func-apply-sipheaders:2] Set("PJSIP/525-000000a1", "SIPHEADERKEYS=") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@func-apply-sipheaders:3] While("PJSIP/525-000000a1", "0") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] app_while.c: Jumping to priority 7
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] pbx.c: Executing [s@func-apply-sipheaders:8] Return("PJSIP/525-000000a1", "") in new stack
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] app_stack.c: Spawn extension (from-internal, 525, 1) exited non-zero on 'PJSIP/525-000000a1'
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] app_stack.c: PJSIP/525-000000a1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] app_dial.c: Called PJSIP/525/sip:[email protected]:5060;uniq=4BC9F237DA6E7D3638FBE60AD85C2
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] app_dial.c: Connected line update to SIP/321-000001de prevented.
[2017-03-07 20:48:22] VERBOSE[4401][C-00000144] app_dial.c: PJSIP/525-000000a1 is ringing
[2017-03-07 20:48:24] VERBOSE[4401][C-00000144] app_macro.c: Spawn extension (macro-dial-one, s, 51) exited non-zero on 'SIP/321-000001de' in macro 'dial-one'
[2017-03-07 20:48:24] VERBOSE[4401][C-00000144] app_macro.c: Spawn extension (macro-exten-vm, s, 16) exited non-zero on 'SIP/321-000001de' in macro 'exten-vm'
[2017-03-07 20:48:24] VERBOSE[4401][C-00000144] pbx.c: Spawn extension (ext-local, 525, 2) exited non-zero on 'SIP/321-000001de'
[2017-03-07 20:48:24] VERBOSE[4401][C-00000144] pbx.c: Executing [h@ext-local:1] Macro("SIP/321-000001de", "hangupcall,") in new stack
[2017-03-07 20:48:24] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/321-000001de", "1?theend") in new stack
[2017-03-07 20:48:24] VERBOSE[4401][C-00000144] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2017-03-07 20:48:24] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/321-000001de", "0?Set(CDR(recordingfile)=)") in new stack
[2017-03-07 20:48:24] VERBOSE[4401][C-00000144] pbx.c: Executing [s@macro-hangupcall:4] Hangup("SIP/321-000001de", "") in new stack
[2017-03-07 20:48:24] VERBOSE[4401][C-00000144] app_macro.c: Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/321-000001de' in macro 'hangupcall'
[2017-03-07 20:48:24] VERBOSE[4401][C-00000144] pbx.c: Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/321-000001de'

I just discovered, that if I place the 525 into a FollowMe, and call the ext. that has the FollowMe, then 525 rings.

so I created an ext. with FollowMe and have it use its FollowM to ring 525

I thought myself a genius.

BUT…

…then I did call the RingGroup and the call again didnt make it through to the extension 525.

I am totaly lost, where to go look and what to check.

Anybody has a good hint for me?

Thanks
Cheers Manne
edit: for good measures: I use PJSIP, as with ChanSip I cant connect my ext. from the remote site, why ever. it gives back a 403. edit: that was a problem with the long secret, with a short one, the FritzBox worked greate.

Edit 2: now i changed to chan_sip and I still have the same probs. dailing the ext. works, calling the ext. via ringgroup does not.

and I still have no clue why

Make sure you’re forwarding RTP (10000-20000) to your PBX.

Also try adding a line to your Asterisk SIP settings (or trunk)

PROGRESSINBAND=yes

also try with

PREMATUREMEDIA=yes or PREMATUREMEDIA=no

I had this issue yesterday on a Grandstream PBX with Bandwidth.com and it was solved by toggling the PREMATUREMEDIA setting, but I’ve also solved it on FreePBX by forwarding RTP and have read reports about the PROGRESSINBAND thing working (Forwarding calls are muted)

Try to disable Send Progress and see if that helps at all.