Circuit Busy

Hi, I have just setup my Asterisk server with FreePBX and all incoming/internal and outbound calls work great apart from some numbers that I dial.

Some numbers give me a busy circuit message; I dialled the number in question and pasted the asterisk log files below to see if someone is able to see what the problem might be.

[ul][Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [914101132434073@from-internal:1] Macro(“SIP/5007-00000002”, “user-callerid,SKIPTTL,”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/5007-00000002”, “AMPUSER=5007”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/5007-00000002”, “0?report”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/5007-00000002”, “1?Set(REALCALLERIDNUM=5007)”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/5007-00000002”, “AMPUSER=5007”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/5007-00000002”, “AMPUSERCIDNAME=Mark Whelan”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/5007-00000002”, “0?report”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/5007-00000002”, “AMPUSERCID=5007”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-user-callerid:8] Set(“SIP/5007-00000002”, “CALLERID(all)=“Mark Whelan” <5007>”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-user-callerid:9] ExecIf(“SIP/5007-00000002”, “0?Set(CHANNEL(language)=)”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-user-callerid:10] GotoIf(“SIP/5007-00000002”, “1?continue”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Goto (macro-user-callerid,s,19)
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-user-callerid:19] NoOp(“SIP/5007-00000002”, “Using CallerID “Mark Whelan” <5007>”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [914101132434073@from-internal:2] Set(“SIP/5007-00000002”, “_NODEST=”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [914101132434073@from-internal:3] Macro(“SIP/5007-00000002”, “record-enable,5007,OUT,”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-record-enable:1] GotoIf(“SIP/5007-00000002”, “1?check”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Goto (macro-record-enable,s,4)
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-record-enable:4] ExecIf(“SIP/5007-00000002”, “0?MacroExit()”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-record-enable:5] GotoIf(“SIP/5007-00000002”, “0?Group:OUT”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Goto (macro-record-enable,s,15)
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-record-enable:15] GotoIf(“SIP/5007-00000002”, “0?IN”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-record-enable:16] ExecIf(“SIP/5007-00000002”, “1?MacroExit()”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [914101132434073@from-internal:4] Macro(“SIP/5007-00000002”, “dialout-trunk,2,14101132434073,”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“SIP/5007-00000002”, “DIAL_TRUNK=2”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/5007-00000002”, “0?sub-pincheck,s,1”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/5007-00000002”, “0?disabletrunk,1”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“SIP/5007-00000002”, “DIAL_NUMBER=14101132434073”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“SIP/5007-00000002”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“SIP/5007-00000002”, “OUTBOUND_GROUP=OUT_2”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/5007-00000002”, “1?nomax”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Goto (macro-dialout-trunk,s,9)
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/5007-00000002”, “0?skipoutcid”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“SIP/5007-00000002”, “DIAL_TRUNK_OPTIONS=”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“SIP/5007-00000002”, “outbound-callerid,2”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/5007-00000002”, “0?Set(CALLERPRES()=)”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/5007-00000002”, “0?Set(REALCALLERIDNUM=5007)”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“SIP/5007-00000002”, “1?normcid”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Goto (macro-outbound-callerid,s,6)
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“SIP/5007-00000002”, “USEROUTCID=”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“SIP/5007-00000002”, “EMERGENCYCID=”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“SIP/5007-00000002”, “TRUNKOUTCID=<+441312080400>”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“SIP/5007-00000002”, “1?trunkcid”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Goto (macro-outbound-callerid,s,12)
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“SIP/5007-00000002”, “1?Set(CALLERID(all)=<+441312080400>)”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“SIP/5007-00000002”, “0?Set(CALLERID(all)=)”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“SIP/5007-00000002”, “1?Set(CALLERID(all)=<+441312080400>)”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/5007-00000002”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:12] ExecIf(“SIP/5007-00000002”, “1?AGI(fixlocalprefix)”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] res_agi.c: – Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Sep 30 15:32:54] VERBOSE[3100] res_agi.c: == fixlocalprefix: Dialpattern 44+. matched. 14101132434073 -> 4414101132434073
[Sep 30 15:32:54] VERBOSE[3100] res_agi.c: – <SIP/5007-00000002>AGI Script fixlocalprefix completed, returning 0
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/5007-00000002”, “OUTNUM=4414101132434073”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/5007-00000002”, “custom=SIP/SKYPEOUT”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/5007-00000002”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^))”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:16] Macro(“SIP/5007-00000002”, “dialout-trunk-predial-hook,”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/5007-00000002”, “”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:17] GotoIf(“SIP/5007-00000002”, “0?bypass,1”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/5007-00000002”, “0?customtrunk”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:19] Dial(“SIP/5007-00000002”, “SIP/SKYPEOUT/4414101132434073,300,”) in new stack
[Sep 30 15:32:54] VERBOSE[3100] netsock.c: == Using SIP RTP TOS bits 184
[Sep 30 15:32:54] VERBOSE[3100] netsock.c: == Using SIP RTP CoS mark 5
[Sep 30 15:32:54] VERBOSE[3100] app_dial.c: – Called SKYPEOUT/4414101132434073
[Sep 30 15:32:55] VERBOSE[3100] app_dial.c: – SIP/SKYPEOUT-00000003 is circuit-busy
[Sep 30 15:32:55] VERBOSE[3100] app_dial.c: == Everyone is busy/congested at this time (1:0/1/0)
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:20] NoOp(“SIP/5007-00000002”, “Dial failed for some reason with DIALSTATUS = CONGESTION and HANGUPCAUSE = 1”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [s@macro-dialout-trunk:21] Goto(“SIP/5007-00000002”, “s-CONGESTION,1”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Goto (macro-dialout-trunk,s-CONGESTION,1)
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [s-CONGESTION@macro-dialout-trunk:1] Set(“SIP/5007-00000002”, “RC=1”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [s-CONGESTION@macro-dialout-trunk:2] Goto(“SIP/5007-00000002”, “1,1”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Goto (macro-dialout-trunk,1,1)
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [1@macro-dialout-trunk:1] Goto(“SIP/5007-00000002”, “continue,1”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Goto (macro-dialout-trunk,continue,1)
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [continue@macro-dialout-trunk:1] GotoIf(“SIP/5007-00000002”, “1?noreport”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Goto (macro-dialout-trunk,continue,3)
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [continue@macro-dialout-trunk:3] NoOp(“SIP/5007-00000002”, “TRUNK Dial failed due to CONGESTION HANGUPCAUSE: 1 - failing through to other trunks”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [continue@macro-dialout-trunk:4] Set(“SIP/5007-00000002”, “CALLERID(number)=5007”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [914101132434073@from-internal:5] Macro(“SIP/5007-00000002”, “outisbusy,”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [s@macro-outisbusy:1] Progress(“SIP/5007-00000002”, “”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [s@macro-outisbusy:2] GotoIf(“SIP/5007-00000002”, “0?emergency,1”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [s@macro-outisbusy:3] GotoIf(“SIP/5007-00000002”, “0?intracompany,1”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] pbx.c: – Executing [s@macro-outisbusy:4] Playback(“SIP/5007-00000002”, “all-circuits-busy-now&pls-try-call-later, noanswer”) in new stack
[Sep 30 15:32:55] VERBOSE[3100] file.c: – <SIP/5007-00000002> Playing ‘all-circuits-busy-now.gsm’ (language ‘en’)
[Sep 30 15:32:56] VERBOSE[3100] app_macro.c: == Spawn extension (macro-outisbusy, s, 4) exited non-zero on ‘SIP/5007-00000002’ in macro ‘outisbusy’
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: == Spawn extension (from-internal, 914101132434073, 5) exited non-zero on ‘SIP/5007-00000002’
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: – Executing [h@from-internal:1] Macro(“SIP/5007-00000002”, “hangupcall”) in new stack
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: – Executing [s@macro-hangupcall:1] GotoIf(“SIP/5007-00000002”, “1?noautomon”) in new stack
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: – Goto (macro-hangupcall,s,3)
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: – Executing [s@macro-hangupcall:3] NoOp(“SIP/5007-00000002”, “TOUCH_MONITOR_OUTPUT=”) in new stack
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: – Executing [s@macro-hangupcall:4] GotoIf(“SIP/5007-00000002”, “1?skiprg”) in new stack
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: – Goto (macro-hangupcall,s,7)
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: – Executing [s@macro-hangupcall:7] GotoIf(“SIP/5007-00000002”, “1?skipblkvm”) in new stack
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: – Goto (macro-hangupcall,s,10)
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: – Executing [s@macro-hangupcall:10] GotoIf(“SIP/5007-00000002”, “1?theend”) in new stack
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: – Goto (macro-hangupcall,s,12)
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: – Executing [s@macro-hangupcall:12] Hangup(“SIP/5007-00000002”, “”) in new stack
[Sep 30 15:32:56] VERBOSE[3100] app_macro.c: == Spawn extension (macro-hangupcall, s, 12) exited non-zero on ‘SIP/5007-00000002’ in macro ‘hangupcall’
[Sep 30 15:32:56] VERBOSE[3100] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/5007-00000002’
[/ul]

It looks to me that the number being dialed in the log below is invalid have you verified that the number works from another service provider.

The busy/congested message is received in several different cirumstances. For instance if you dial an invalid number the provider will send you a 404 route not found. When looking at the asterisk logs this will show busy/congested, another example would be if you dialed a number that was truly busy you will receive a 486 busy which will also show busy/congested in the asterisk log.

Cause 1 is number unallocated. You will need to turn on debugging in the skype module to find more. Note, that module isn’t part of the open source Asterisk. It appears to be a commercial product from Digium, in which case you should probably request support from them (although this is hosted by Digium, it is not used for supporting their commercial products).

I found the problem and sorted it, the problem was that the system I was calling did not support the codec that I was using, hence the reason that 95% of my outbound calls were ok just the odd 5% that got the circuit busy message.