IAX calls "All circuits are busy" after upgrade

Hello team,

After upgrading my FreePBX from v13.0.195.12 to v14.0.3.18 inbound/outbound calls to the public network and calls to extensions on the same PBX work - so far so good. However, when I try to make calls to our other two remote FreePBX’s via IAX I get the message “All circuits are busy”.

The remote FreePBX’s are running v13.0.195.12

I used the method here to upgrade: Sangoma Documentation

In the log file below, I am calling from extension 781 on the upgraded PBX to remote site extension 707.

I do see a warning about g729 to ulaw

[2018-09-26 18:52:50] WARNING[6012][C-00000008]: chan_iax2.c:12557 iax2_request: Unable to create translator path for (g729) to (ulaw) on IAX2/TO-BEL-22149

Log file:

Blockquote
Asterisk 13.22.0, Copyright (C) 1999 - 2014, Digium, Inc. and others.

Created by Mark Spencer <[email protected]>

Asterisk comes with ABSOLUTELY NO WARRANTY; type ‘core show warranty’ for details.

This is free software, with components licensed under the GNU General Public

License version 2 and other licenses; you are welcome to redistribute it under

certain conditions. Type ‘core show license’ for details.

=========================================================================

Connected to Asterisk 13.22.0 currently running on localhost (pid = 2072)

== Using SIP RTP TOS bits 184

== Using SIP RTP CoS mark 5

– Executing [707@from-internal:1] Macro("SIP/781-0000000e", "user-callerid,LIMIT,EXTERNAL,") in new stack

– Executing [s@macro-user-callerid:1] Set("SIP/781-0000000e", "TOUCH_MONITOR=1537984370.103") in new stack

– Executing [s@macro-user-callerid:2] Set("SIP/781-0000000e", "AMPUSER=781") in new stack

– Executing [s@macro-user-callerid:3] GotoIf("SIP/781-0000000e", "0?report") in new stack

– Executing [s@macro-user-callerid:4] ExecIf("SIP/781-0000000e", "1?Set(REALCALLERIDNUM=781)") in new stack

– Executing [s@macro-user-callerid:5] Set("SIP/781-0000000e", "AMPUSER=781") in new stack

– Executing [s@macro-user-callerid:6] GotoIf("SIP/781-0000000e", "0?limit") in new stack

– Executing [s@macro-user-callerid:7] Set("SIP/781-0000000e", "AMPUSERCIDNAME=Stephen Murphy") in new stack

– Executing [s@macro-user-callerid:8] ExecIf("SIP/781-0000000e", "0?Set(__CIDMASQUERADING=TRUE)") in new stack

– Executing [s@macro-user-callerid:9] GotoIf("SIP/781-0000000e", "0?report") in new stack

– Executing [s@macro-user-callerid:10] Set("SIP/781-0000000e", "AMPUSERCID=781") in new stack

– Executing [s@macro-user-callerid:11] Set("SIP/781-0000000e", "__DIAL_OPTIONS=Ttr") in new stack

– Executing [s@macro-user-callerid:12] Set("SIP/781-0000000e", "CALLERID(all)="Stephen Murphy" <781>") in new stack

– Executing [s@macro-user-callerid:13] GotoIf("SIP/781-0000000e", "0?limit") in new stack

– Executing [s@macro-user-callerid:14] ExecIf("SIP/781-0000000e", "1?Set(GROUP(concurrency_limit)=781)") in new stack

– Executing [s@macro-user-callerid:15] ExecIf("SIP/781-0000000e", "0?Set(CHANNEL(language)=)") in new stack

– Executing [s@macro-user-callerid:16] NoOp("SIP/781-0000000e", "Macro Depth is 1") in new stack

– Executing [s@macro-user-callerid:17] GotoIf("SIP/781-0000000e", "1?report2:macroerror") in new stack

– Goto (macro-user-callerid,s,18)

– Executing [s@macro-user-callerid:18] GotoIf("SIP/781-0000000e", "1?continue") in new stack

– Goto (macro-user-callerid,s,37)

– Executing [s@macro-user-callerid:37] Set("SIP/781-0000000e", "CALLERID(number)=781") in new stack

– Executing [s@macro-user-callerid:38] Set("SIP/781-0000000e", "CALLERID(name)=Stephen Murphy") in new stack

– Executing [s@macro-user-callerid:39] GotoIf("SIP/781-0000000e", "0?cnum") in new stack

– Executing [s@macro-user-callerid:40] Set("SIP/781-0000000e", "CDR(cnam)=Stephen Murphy") in new stack

– Executing [s@macro-user-callerid:41] Set("SIP/781-0000000e", "CDR(cnum)=781") in new stack

– Executing [s@macro-user-callerid:42] Set("SIP/781-0000000e", "CHANNEL(language)=en") in new stack

– Executing [707@from-internal:2] Gosub("SIP/781-0000000e", "sub-record-check,s,1(out,707,dontcare)") in new stack

– Executing [s@sub-record-check:1] GotoIf("SIP/781-0000000e", "0?initialized") in new stack

– Executing [s@sub-record-check:2] Set("SIP/781-0000000e", "__REC_STATUS=INITIALIZED") in new stack

– Executing [s@sub-record-check:3] Set("SIP/781-0000000e", "NOW=1537984370") in new stack

– Executing [s@sub-record-check:4] Set("SIP/781-0000000e", "__DAY=26") in new stack

– Executing [s@sub-record-check:5] Set("SIP/781-0000000e", "__MONTH=09") in new stack

– Executing [s@sub-record-check:6] Set("SIP/781-0000000e", "__YEAR=2018") in new stack

– Executing [s@sub-record-check:7] Set("SIP/781-0000000e", "__TIMESTR=20180926-185250") in new stack

– Executing [s@sub-record-check:8] Set("SIP/781-0000000e", "__FROMEXTEN=781") in new stack

– Executing [s@sub-record-check:9] Set("SIP/781-0000000e", "__MON_FMT=wav") in new stack

– Executing [s@sub-record-check:10] NoOp("SIP/781-0000000e", "Recordings initialized") in new stack

– Executing [s@sub-record-check:11] ExecIf("SIP/781-0000000e", "0?Set(ARG3=dontcare)") in new stack

– Executing [s@sub-record-check:12] Set("SIP/781-0000000e", "REC_POLICY_MODE_SAVE=") in new stack

– Executing [s@sub-record-check:13] ExecIf("SIP/781-0000000e", "0?Set(REC_STATUS=NO)") in new stack

– Executing [s@sub-record-check:14] GotoIf("SIP/781-0000000e", "3?checkaction") in new stack

– Goto (sub-record-check,s,17)

– Executing [s@sub-record-check:17] GotoIf("SIP/781-0000000e", "1?sub-record-check,out,1") in new stack

– Goto (sub-record-check,out,1)

– Executing [out@sub-record-check:1] NoOp("SIP/781-0000000e", "Outbound Recording Check from 781 to 707") in new stack

– Executing [out@sub-record-check:2] Set("SIP/781-0000000e", "RECMODE=dontcare") in new stack

– Executing [out@sub-record-check:3] ExecIf("SIP/781-0000000e", "1?Goto(routewins)") in new stack

– Goto (sub-record-check,out,7)

– Executing [out@sub-record-check:7] Gosub("SIP/781-0000000e", "recordcheck,1(dontcare,out,707)") in new stack

– Executing [recordcheck@sub-record-check:1] NoOp("SIP/781-0000000e", "Starting recording check against dontcare") in new stack

– Executing [recordcheck@sub-record-check:2] Goto("SIP/781-0000000e", "dontcare") in new stack

– Goto (sub-record-check,recordcheck,3)

– Executing [recordcheck@sub-record-check:3] Return("SIP/781-0000000e", "") in new stack

– Executing [out@sub-record-check:8] Return("SIP/781-0000000e", "") in new stack

– Executing [707@from-internal:3] ExecIf("SIP/781-0000000e", "0 ?Set(CDR(accountcode)=)") in new stack

– Executing [707@from-internal:4] Set("SIP/781-0000000e", "INTRACOMPANYROUTE=YES") in new stack

– Executing [707@from-internal:5] Set("SIP/781-0000000e", "MOHCLASS=default") in new stack

– Executing [707@from-internal:6] Set("SIP/781-0000000e", "_NODEST=") in new stack

– Executing [707@from-internal:7] Macro("SIP/781-0000000e", "dialout-trunk,4,707,off") in new stack

– Executing [s@macro-dialout-trunk:1] Set("SIP/781-0000000e", "DIAL_TRUNK=4") in new stack

– Executing [s@macro-dialout-trunk:2] ExecIf("SIP/781-0000000e", "0?Set(DIAL_OPTIONS=tr)") in new stack

– Executing [s@macro-dialout-trunk:3] GosubIf("SIP/781-0000000e", "0?sub-pincheck,s,1()") in new stack

– Executing [s@macro-dialout-trunk:4] ExecIf("SIP/781-0000000e", "0?Set(CALLERID(num)=781)") in new stack

– Executing [s@macro-dialout-trunk:5] GotoIf("SIP/781-0000000e", "0?disabletrunk,1") in new stack

– Executing [s@macro-dialout-trunk:6] Set("SIP/781-0000000e", "DIAL_NUMBER=707") in new stack

– Executing [s@macro-dialout-trunk:7] Set("SIP/781-0000000e", "DIAL_TRUNK_OPTIONS=Ttr") in new stack

– Executing [s@macro-dialout-trunk:8] Set("SIP/781-0000000e", "OUTBOUND_GROUP=OUT_4") in new stack

– Executing [s@macro-dialout-trunk:9] Set("SIP/781-0000000e", "DIAL_TRUNK_OPTIONS=Tt") in new stack

– Executing [s@macro-dialout-trunk:10] GotoIf("SIP/781-0000000e", "1?nomax") in new stack

– Goto (macro-dialout-trunk,s,12)

– Executing [s@macro-dialout-trunk:12] GotoIf("SIP/781-0000000e", "1?skipoutcid") in new stack

– Goto (macro-dialout-trunk,s,14)

– Executing [s@macro-dialout-trunk:14] GosubIf("SIP/781-0000000e", "0?sub-flp-4,s,1()") in new stack

– Executing [s@macro-dialout-trunk:15] Set("SIP/781-0000000e", "OUTNUM=707") in new stack

– Executing [s@macro-dialout-trunk:16] Set("SIP/781-0000000e", "custom=IAX2/TO-BEL") in new stack

– Executing [s@macro-dialout-trunk:17] ExecIf("SIP/781-0000000e", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack

– Executing [s@macro-dialout-trunk:18] ExecIf("SIP/781-0000000e", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack

– Executing [s@macro-dialout-trunk:19] Macro("SIP/781-0000000e", "dialout-trunk-predial-hook,") in new stack

– Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/781-0000000e", "") in new stack

– Executing [s@macro-dialout-trunk:20] GotoIf("SIP/781-0000000e", "0?bypass,1") in new stack

– Executing [s@macro-dialout-trunk:21] ExecIf("SIP/781-0000000e", "1?Set(CONNECTEDLINE(num,i)=707)") in new stack

– Executing [s@macro-dialout-trunk:22] ExecIf("SIP/781-0000000e", "1?Set(CONNECTEDLINE(name,i)=CID:781)") in new stack

– Executing [s@macro-dialout-trunk:23] ExecIf("SIP/781-0000000e", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)781)") in new stack

– Executing [s@macro-dialout-trunk:24] GotoIf("SIP/781-0000000e", "0?customtrunk") in new stack

– Executing [s@macro-dialout-trunk:25] Dial("SIP/781-0000000e", "IAX2/TO-BEL/707,300,Ttb(func-apply-sipheaders^s^1)") in new stack

[2018-09-26 18:52:50] WARNING[6012][C-00000008]: chan_iax2.c:12557 iax2_request: Unable to create translator path for (g729) to (ulaw) on IAX2/TO-BEL-22149

– Hungup ‘IAX2/TO-BEL-22149’

[2018-09-26 18:52:50] WARNING[6012][C-00000008]: app_dial.c:2527 dial_exec_full: Unable to create channel of type ‘IAX2’ (cause 0 - Unknown)

== Everyone is busy/congested at this time (1:0/0/1)

– Executing [s@macro-dialout-trunk:26] NoOp("SIP/781-0000000e", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 0") in new stack

– Executing [s@macro-dialout-trunk:27] GotoIf("SIP/781-0000000e", "0?continue,1:s-CHANUNAVAIL,1") in new stack

– Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)

– Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("SIP/781-0000000e", "RC=0") in new stack

– Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("SIP/781-0000000e", "0,1") in new stack

– Goto (macro-dialout-trunk,0,1)

– Executing [0@macro-dialout-trunk:1] Goto("SIP/781-0000000e", "continue,1") in new stack

– Goto (macro-dialout-trunk,continue,1)

– Executing [continue@macro-dialout-trunk:1] NoOp("SIP/781-0000000e", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 0 - failing through to other trunks") in new stack

– Executing [continue@macro-dialout-trunk:2] ExecIf("SIP/781-0000000e", "1?Set(CALLERID(number)=781)") in new stack

– Executing [707@from-internal:8] Macro("SIP/781-0000000e", "outisbusy,") in new stack

– Executing [s@macro-outisbusy:1] Progress("SIP/781-0000000e", "") in new stack

– Executing [s@macro-outisbusy:2] GotoIf("SIP/781-0000000e", "0?emergency,1") in new stack

– Executing [s@macro-outisbusy:3] GotoIf("SIP/781-0000000e", "1?intracompany,1") in new stack

– Goto (macro-outisbusy,intracompany,1)

– Executing [intracompany@macro-outisbusy:1] Playback("SIP/781-0000000e", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack

– <SIP/781-0000000e> Playing ‘all-circuits-busy-now.ulaw’ (language ‘en’)

– <SIP/781-0000000e> Playing ‘please-try-call-later.ulaw’ (language ‘en’)

– Executing [intracompany@macro-outisbusy:2] Congestion("SIP/781-0000000e", "20") in new stack

[2018-09-26 18:52:54] WARNING[6012][C-00000008]: channel.c:5080 ast_prod: Prodding channel ‘SIP/781-0000000e’ failed

== Spawn extension (macro-outisbusy, intracompany, 2) exited non-zero on ‘SIP/781-0000000e’ in macro ‘outisbusy’

== Spawn extension (from-internal, 707, 8) exited non-zero on ‘SIP/781-0000000e’

– Executing [h@from-internal:1] Macro("SIP/781-0000000e", "hangupcall") in new stack

– Executing [s@macro-hangupcall:1] GotoIf("SIP/781-0000000e", "1?theend") in new stack

– Goto (macro-hangupcall,s,3)

– Executing [s@macro-hangupcall:3] ExecIf("SIP/781-0000000e", "0?Set(CDR(recordingfile)=)") in new stack

– Executing [s@macro-hangupcall:4] NoOp("SIP/781-0000000e", " monior file= ") in new stack

– Executing [s@macro-hangupcall:5] AGI("SIP/781-0000000e", "attendedtransfer-rec-restart.php,") in new stack

– Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php

– <SIP/781-0000000e>AGI Script attendedtransfer-rec-restart.php completed, returning 0

– Executing [s@macro-hangupcall:6] Hangup("SIP/781-0000000e", "") in new stack

== Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘SIP/781-0000000e’ in macro ‘hangupcall’

== Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/781-0000000e’

localhost*CLI>

Disconnected from Asterisk server

Asterisk cleanly ending (0).

Executing last minute cleanups

Blockquote

Do you have enough licensed versions of g729 or an installed version of the bcg open source codec ?

Thanks for the pointer Dicko. For testing, I changed to gsm and it worked as expected. Now I will try to compile g729 for my PBX as it seems this is what the previous admin did using instructions from http://asterisk.hosting.lv/

Thanks again for your support…

Do you have a bandwidth or other limitation that still requires using G.729? Otherwise, just switch everything to G.711 or better; G.729 is a significant quality degradation, especially if one or both ends of the overall call are mobiles. It can also cause DTMF reliability issues.

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