FreePBX automatically redials after call rejection

General Help
1

Hello all,

I’m facing a very strange issue recently with my FreePBX server.
When I place a call using any trunk on my system, I never get a busy state signal if the other party rejects my call.

This is very annoying for the other parties because everytime they reject my call, they receive another call seconds later.
And this goes on and on until I decide to hangup.
On my side, I never get a hangup or busy signal when the other party rejects the call. I simply continue to hear the normal dialing signal, and I have no idea that the other party is constantly rejecting my calls.

I must have messed up some configuration on my server but I have no idea whatsoever how to understand what’s going on. I tried to read asterisk log files, but I really don’t understand the messages.

Do you guys have any idea of what could be happening here?
I have only 1 trunk setup on my system.

I have tried to replace that trunk by another provider, but the problem did not go away.

Thanks.
Best Regards,
Pedro

2

This sounds like a problem with your SIP carrier. If you can provide a SIP DEBUG session of an example, the folks here can probably spot the problem in no time. Of course, you also do it the old fashioned way and ask your provider for help. If it’s something new, the problem could be on their end as well.

3

Well, here is the output of a dial session.
I rejected the call 6 times (I suppose…):

[2017-06-09 00:41:10] VERBOSE[2883][C-00000068] netsock2.c: == Using SIP RTP TOS bits 184
[2017-06-09 00:41:10] VERBOSE[2883][C-00000068] netsock2.c: == Using SIP RTP CoS mark 5
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [924353223@from-internal:1] Macro(“SIP/150-000000c5”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/150-000000c5”, “TOUCH_MONITOR=1496965270.223”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:2] Set(“SIP/150-000000c5”, “AMPUSER=150”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:3] GotoIf(“SIP/150-000000c5”, “0?report”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:4] ExecIf(“SIP/150-000000c5”, “1?Set(REALCALLERIDNUM=150)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/150-000000c5”, “AMPUSER=150”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/150-000000c5”, “0?limit”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/150-000000c5”, “AMPUSERCIDNAME=Casa - Voip”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:8] GotoIf(“SIP/150-000000c5”, “0?report”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:9] Set(“SIP/150-000000c5”, “AMPUSERCID=150”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:10] Set(“SIP/150-000000c5”, “__DIAL_OPTIONS=tr”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:11] Set(“SIP/150-000000c5”, “CALLERID(all)=“Casa - Voip” <150>”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:12] GotoIf(“SIP/150-000000c5”, “0?limit”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:13] ExecIf(“SIP/150-000000c5”, “1?Set(GROUP(concurrency_limit)=150)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:14] GotoIf(“SIP/150-000000c5”, “1?continue”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Goto (macro-user-callerid,s,27)
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:27] Set(“SIP/150-000000c5”, “CALLERID(number)=150”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:28] Set(“SIP/150-000000c5”, “CALLERID(name)=Casa - Voip”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:29] Set(“SIP/150-000000c5”, “CDR(cnum)=150”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:30] Set(“SIP/150-000000c5”, “CDR(cnam)=Casa - Voip”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-user-callerid:31] Set(“SIP/150-000000c5”, “CHANNEL(language)=pt-br”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [924353223@from-internal:2] Set(“SIP/150-000000c5”, “MOHCLASS=default”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [924353223@from-internal:3] Set(“SIP/150-000000c5”, “_NODEST=”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [924353223@from-internal:4] Gosub(“SIP/150-000000c5”, “sub-record-check,s,1(out,924353223,)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:1] Set(“SIP/150-000000c5”, “REC_POLICY_MODE_SAVE=”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:2] GotoIf(“SIP/150-000000c5”, “1?check”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Goto (sub-record-check,s,7)
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:7] Set(“SIP/150-000000c5”, “__MON_FMT=g729”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:8] GotoIf(“SIP/150-000000c5”, “1?next”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Goto (sub-record-check,s,11)
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:11] ExecIf(“SIP/150-000000c5”, “0?Return()”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:12] ExecIf(“SIP/150-000000c5”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:13] GotoIf(“SIP/150-000000c5”, “0?out,1”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:14] Set(“SIP/150-000000c5”, “__REC_STATUS=INITIALIZED”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:15] Set(“SIP/150-000000c5”, “NOW=1496965270”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:16] Set(“SIP/150-000000c5”, “__DAY=09”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:17] Set(“SIP/150-000000c5”, “__MONTH=06”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:18] Set(“SIP/150-000000c5”, “__YEAR=2017”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:19] Set(“SIP/150-000000c5”, “__TIMESTR=20170609-004110”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:20] Set(“SIP/150-000000c5”, “__FROMEXTEN=150”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:21] Set(“SIP/150-000000c5”, “__CALLFILENAME=out-924353223-150-20170609-004110-1496965270.223”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@sub-record-check:22] Goto(“SIP/150-000000c5”, “out,1”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Goto (sub-record-check,out,1)
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [out@sub-record-check:1] ExecIf(“SIP/150-000000c5”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [out@sub-record-check:2] GosubIf(“SIP/150-000000c5”, “0?record,1(exten,924353223,150)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [out@sub-record-check:3] Return(“SIP/150-000000c5”, “”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [924353223@from-internal:5] Macro(“SIP/150-000000c5”, “dialout-trunk,2,00351924353223,on”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“SIP/150-000000c5”, “DIAL_TRUNK=2”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/150-000000c5”, “0?sub-pincheck,s,1()”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/150-000000c5”, “0?disabletrunk,1”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“SIP/150-000000c5”, “DIAL_NUMBER=00351924353223”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“SIP/150-000000c5”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“SIP/150-000000c5”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/150-000000c5”, “0?nomax”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:8] GotoIf(“SIP/150-000000c5”, “0?chanfull”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/150-000000c5”, “0?skipoutcid”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“SIP/150-000000c5”, “DIAL_TRUNK_OPTIONS=”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“SIP/150-000000c5”, “outbound-callerid,2”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/150-000000c5”, “0?Set(CALLERPRES()=)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/150-000000c5”, “0?Set(REALCALLERIDNUM=150)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“SIP/150-000000c5”, “1?normcid”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“SIP/150-000000c5”, “USEROUTCID=”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“SIP/150-000000c5”, “EMERGENCYCID=”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“SIP/150-000000c5”, “TRUNKOUTCID=+351212345673”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“SIP/150-000000c5”, “1?trunkcid”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Goto (macro-outbound-callerid,s,14)
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“SIP/150-000000c5”, “1?Set(CALLERID(all)=+351212345673)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/150-000000c5”, “0?Set(CALLERID(all)=)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:16] ExecIf(“SIP/150-000000c5”, “0?Set(CALLERID(all)=)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:17] ExecIf(“SIP/150-000000c5”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:18] Set(“SIP/150-000000c5”, “CDR(outbound_cnum)=+351212345673”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-outbound-callerid:19] Set(“SIP/150-000000c5”, “CDR(outbound_cnam)=”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/150-000000c5”, “0?sub-flp-2,s,1()”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/150-000000c5”, “OUTNUM=00351924353223”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/150-000000c5”, “custom=SIP/megavoip”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/150-000000c5”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/150-000000c5”, “0?Set(DIAL_TRUNK_OPTIONS=M(confirm))”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“SIP/150-000000c5”, “dialout-trunk-predial-hook,”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/150-000000c5”, “”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/150-000000c5”, “0?bypass,1”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/150-000000c5”, “1?Set(CONNECTEDLINE(num,i)=00351924353223)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/150-000000c5”, “1?Set(CONNECTEDLINE(name,i)=CID:+351212345673)”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“SIP/150-000000c5”, “0?customtrunk”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“SIP/150-000000c5”, “SIP/megavoip/00351924353223,300,”) in new stack
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] netsock2.c: == Using SIP RTP TOS bits 184
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] netsock2.c: == Using SIP RTP CoS mark 5
[2017-06-09 00:41:10] VERBOSE[26012][C-00000068] app_dial.c: – Called SIP/megavoip/00351924353223
[2017-06-09 00:41:11] VERBOSE[26012][C-00000068] app_dial.c: – SIP/megavoip-000000c6 is making progress passing it to SIP/150-000000c5
[2017-06-09 00:42:59] VERBOSE[26012][C-00000068] app_macro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on ‘SIP/150-000000c5’ in macro ‘dialout-trunk’
[2017-06-09 00:42:59] VERBOSE[26012][C-00000068] pbx.c: == Spawn extension (from-internal, 924353223, 5) exited non-zero on ‘SIP/150-000000c5’
[2017-06-09 00:42:59] VERBOSE[26012][C-00000068] pbx.c: – Executing [h@from-internal:1] Hangup(“SIP/150-000000c5”, “”) in new stack
[2017-06-09 00:42:59] VERBOSE[26012][C-00000068] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/150-000000c5’