SIP Trunk behaving strange: Please help

I am unable to make outgoing calls or receive incoming calls with my SIP trunk. My Log file contains the following which indicates that the SIP Trunk has not registered with the provider. However, when I check on my VOIP Provider, I do see the FBPX logged in as a SIP Client.

[2012-02-16 05:31:43] ERROR[3653] netsock2.c: getaddrinfo(“sip.voipprovider.com”, “(null)”, …): Name or service not known
[2012-02-16 05:31:43] WARNING[3653] acl.c: Unable to lookup ‘sip.voipprovider.com
[2012-02-16 05:32:43] ERROR[3653] netsock2.c: getaddrinfo(“sip.voipprovider.com”, “(null)”, …): Name or service not known
[2012-02-16 05:32:43] WARNING[3653] acl.c: Unable to lookup ‘sip.voipprovider.com
[2012-02-16 05:32:43] NOTICE[3653] chan_sip.c: – Registration for ‘[email protected]’ timed out, trying again (Attempt #2)

Could someone please help? Below is the log for the actual attempt to dial out:

[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [18005558355@from-internal:1] Macro(“SIP/152-00000000”, “user-callerid,LIMIT,”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:1] Set(“SIP/152-00000000”, “AMPUSER=152”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/152-00000000”, “0?report”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/152-00000000”, “1?Set(REALCALLERIDNUM=152)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:4] Set(“SIP/152-00000000”, “AMPUSER=152”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:5] Set(“SIP/152-00000000”, “AMPUSERCIDNAME=Mediacenter”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/152-00000000”, “0?report”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:7] Set(“SIP/152-00000000”, “AMPUSERCID=152”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:8] Set(“SIP/152-00000000”, “CALLERID(all)=“Mediacenter” <152>”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:9] GotoIf(“SIP/152-00000000”, “0?limit”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:10] ExecIf(“SIP/152-00000000”, “1?Set(GROUP(concurrency_limit)=152)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:11] ExecIf(“SIP/152-00000000”, “0?Set(CHANNEL(language)=)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:12] GosubIf(“SIP/152-00000000”, “0?sub-ccss,s,1(from-internal,18005558355)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:13] GotoIf(“SIP/152-00000000”, “1?continue”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Goto (macro-user-callerid,s,26)
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:26] Set(“SIP/152-00000000”, “CALLERID(number)=152”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:27] Set(“SIP/152-00000000”, “CALLERID(name)=Mediacenter”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-user-callerid:28] Set(“SIP/152-00000000”, “CHANNEL(language)=en”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [18005558355@from-internal:2] Set(“SIP/152-00000000”, “MOHCLASS=default”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [18005558355@from-internal:3] Set(“SIP/152-00000000”, “_NODEST=”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [18005558355@from-internal:4] Gosub(“SIP/152-00000000”, “sub-record-check,s,1(out,18005558355,)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:1] GotoIf(“SIP/152-00000000”, “1?check”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Goto (sub-record-check,s,3)
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:3] Set(“SIP/152-00000000”, “MON_FMT=wav”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:4] GotoIf(“SIP/152-00000000”, “1?next”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Goto (sub-record-check,s,7)
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:7] ExecIf(“SIP/152-00000000”, “0?Return()”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:8] GotoIf(“SIP/152-00000000”, “0?out,1”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:9] Set(“SIP/152-00000000”, “__REC_STATUS=INITIALIZED”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:10] ExecIf(“SIP/152-00000000”, “0?Set(__REC_POLICY_MODE=)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:11] Set(“SIP/152-00000000”, “NOW=1329400072”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:12] Set(“SIP/152-00000000”, “__DAY=16”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:13] Set(“SIP/152-00000000”, “__MONTH=02”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:14] Set(“SIP/152-00000000”, “__YEAR=2012”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:15] Set(“SIP/152-00000000”, “__TIMESTR=20120216-064752”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:16] Set(“SIP/152-00000000”, “__FROMEXTEN=152”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:17] Set(“SIP/152-00000000”, “__CALLFILENAME=out-18005558355-152-20120216-064752-1329400072.0”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@sub-record-check:18] Goto(“SIP/152-00000000”, “out,1”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Goto (sub-record-check,out,1)
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [out@sub-record-check:1] ExecIf(“SIP/152-00000000”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [out@sub-record-check:2] GosubIf(“SIP/152-00000000”, “0?record,1(exten,18005558355,152)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [out@sub-record-check:3] Return(“SIP/152-00000000”, “”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [18005558355@from-internal:5] Macro(“SIP/152-00000000”, “dialout-trunk,2,18005558355,”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:1] Set(“SIP/152-00000000”, “DIAL_TRUNK=2”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/152-00000000”, “0?sub-pincheck,s,1()”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/152-00000000”, “0?disabletrunk,1”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:4] Set(“SIP/152-00000000”, “DIAL_NUMBER=18005558355”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:5] Set(“SIP/152-00000000”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:6] Set(“SIP/152-00000000”, “OUTBOUND_GROUP=OUT_2”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/152-00000000”, “0?nomax”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:8] GotoIf(“SIP/152-00000000”, “0?chanfull”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/152-00000000”, “0?skipoutcid”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:10] Set(“SIP/152-00000000”, “DIAL_TRUNK_OPTIONS=”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:11] Macro(“SIP/152-00000000”, “outbound-callerid,2”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/152-00000000”, “0?Set(CALLERPRES()=)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/152-00000000”, “0?Set(REALCALLERIDNUM=152)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“SIP/152-00000000”, “1?normcid”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Goto (macro-outbound-callerid,s,6)
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outbound-callerid:6] Set(“SIP/152-00000000”, “USEROUTCID=”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outbound-callerid:7] Set(“SIP/152-00000000”, “EMERGENCYCID=”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outbound-callerid:8] Set(“SIP/152-00000000”, “TRUNKOUTCID=NNNNNNNNN”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“SIP/152-00000000”, “1?trunkcid”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Goto (macro-outbound-callerid,s,12)
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“SIP/152-00000000”, “1?Set(CALLERID(all)=NNNNNNNNN)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“SIP/152-00000000”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“SIP/152-00000000”, “0?Set(CALLERID(all)=)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outbound-callerid:15] ExecIf(“SIP/152-00000000”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:12] GosubIf(“SIP/152-00000000”, “0?sub-flp-2,s,1()”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/152-00000000”, “OUTNUM=18005558355”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/152-00000000”, “custom=SIP/VOIPO”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/152-00000000”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:16] ExecIf(“SIP/152-00000000”, “0?Set(DIAL_TRUNK_OPTIONS=M(confirm))”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:17] Macro(“SIP/152-00000000”, “dialout-trunk-predial-hook,”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/152-00000000”, “”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/152-00000000”, “0?bypass,1”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:19] ExecIf(“SIP/152-00000000”, “1?Set(CONNECTEDLINE(num,i)=18005558355)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:20] ExecIf(“SIP/152-00000000”, “1?Set(CONNECTEDLINE(name,i)=CID:NNNNNNNNN)”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:21] GotoIf(“SIP/152-00000000”, “0?customtrunk”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:22] Dial(“SIP/152-00000000”, “SIP/VOIPProvider/18005558355,300,”) in new stack
[2012-02-16 04:47:52] WARNING[8110] app_dial.c: Unable to create channel of type ‘SIP’ (cause 20 - Unknown)
[2012-02-16 04:47:52] VERBOSE[8110] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:23] NoOp(“SIP/152-00000000”, “Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 20”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-dialout-trunk:24] Goto(“SIP/152-00000000”, “s-CHANUNAVAIL,1”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set(“SIP/152-00000000”, “RC=20”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto(“SIP/152-00000000”, “20,1”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Goto (macro-dialout-trunk,20,1)
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [20@macro-dialout-trunk:1] Goto(“SIP/152-00000000”, “continue,1”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Goto (macro-dialout-trunk,continue,1)
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [continue@macro-dialout-trunk:1] GotoIf(“SIP/152-00000000”, “1?noreport”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Goto (macro-dialout-trunk,continue,3)
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [continue@macro-dialout-trunk:3] NoOp(“SIP/152-00000000”, “TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 20 - failing through to other trunks”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [continue@macro-dialout-trunk:4] Set(“SIP/152-00000000”, “CALLERID(number)=152”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [18005558355@from-internal:6] Macro(“SIP/152-00000000”, “outisbusy,”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outisbusy:1] Progress(“SIP/152-00000000”, “”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outisbusy:2] GotoIf(“SIP/152-00000000”, “0?emergency,1”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outisbusy:3] GotoIf(“SIP/152-00000000”, “0?intracompany,1”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] pbx.c: – Executing [s@macro-outisbusy:4] Playback(“SIP/152-00000000”, “all-circuits-busy-now&pls-try-call-later, noanswer”) in new stack
[2012-02-16 04:47:52] VERBOSE[8110] file.c: – <SIP/152-00000000> Playing ‘all-circuits-busy-now.ulaw’ (language ‘en’)
[2012-02-16 04:47:54] VERBOSE[8110] app_macro.c: == Spawn extension (macro-outisbusy, s, 4) exited non-zero on ‘SIP/152-00000000’ in macro ‘outisbusy’
[2012-02-16 04:47:54] VERBOSE[8110] pbx.c: == Spawn extension (from-internal, 18005558355, 6) exited non-zero on ‘SIP/152-00000000’
[2012-02-16 04:47:54] VERBOSE[8110] pbx.c: – Executing [h@from-internal:1] Hangup(“SIP/152-00000000”, “”) in new stack
[2012-02-16 04:47:54] VERBOSE[8110] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/152-00000000’
[2012-02-16 06:48:39] NOTICE[3653] chan_sip.c: Peer ‘152’ is now UNREACHABLE! Last qualify: 108

There could be many reasons for registration request time out. Kindly check by disabling any firewall you have on your server by issuing

service iptables stop

with following kind of out put.

Flushing firewall rules: [ OK ]
Setting chains to policy ACCEPT: nat filter [ OK ]
Unloading iptables modules: [ OK ]

Also do a ping or telnet to the host to check if it is accessible.