Trunks/Routes

FreePBX Installation / Upgrade
1

Hello all-

I am in the process of upgrading our Trixbox server to the latest version of Distro. I have a spare server that I am installing it on so I can revert back to the old one as needed. So far, I have the phones (polycom) provisioning properly and internal calling works fine.

The main problem I am experiencing now is inbound and outbound calling. When I make an inbound phone call it gives a busy signal. When I make an outbound phone call I get our IVR (Main Menu). The signal comes in through a ADTRAN H2TUR card into a Sangoma T1 card (same hardware as old server).

Does anyone have any insight on where to look? I think I have the settings the same as on the old one but obviously a lot has changed since Trixbox. If any logs or configs are needed and/or would be helpful, please let me know and I will post. Thanks in advance for any help…

Nathan

2

Anyone have any thoughts/suggestions? Or do I need to post this under a different category? Thanks again for any help. It is greatly appreciated.

Nathan

3

Look at Asterisk while you’re making a call. It will probably tell you what the problem is; it will definitely tell you what it’s trying to do when you’re making a call.

4

When making an outgoing phone call, this is what the log shows…

[2013-06-28 12:33:57] VERBOSE[898][C-00000001] netsock2.c: [2013-06-28 12:33:57] == Using SIP RTP TOS bits 184
[2013-06-28 12:33:57] VERBOSE[898][C-00000001] netsock2.c: [2013-06-28 12:33:57] == Using SIP RTP CoS mark 5
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [93864350@from-trunk:1] NoOp(“SIP/310-00000001”, “Catch-All DID Match - Found 93864350 - You probably want a DID for this.”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [93864350@from-trunk:2] Goto(“SIP/310-00000001”, “ext-did,s,1”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Goto (ext-did,s,1)
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ext-did:1] ExecIf(“SIP/310-00000001”, “1?Set(__FROM_DID=s)”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ext-did:2] Gosub(“SIP/310-00000001”, “app-blacklist-check,s,1()”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@app-blacklist-check:1] GotoIf(“SIP/310-00000001”, “0?blacklisted”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@app-blacklist-check:2] Set(“SIP/310-00000001”, “CALLED_BLACKLIST=1”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@app-blacklist-check:3] Return(“SIP/310-00000001”, “”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ext-did:3] Set(“SIP/310-00000001”, “CDR(did)=s”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ext-did:4] ExecIf(“SIP/310-00000001”, “0 ?Set(CALLERID(name)=310)”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ext-did:5] Set(“SIP/310-00000001”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ext-did:6] Set(“SIP/310-00000001”, “CALLERPRES()=allowed_not_screened”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ext-did:7] Goto(“SIP/310-00000001”, “ivr-1,s,1”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Goto (ivr-1,s,1)
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ivr-1:1] Set(“SIP/310-00000001”, “TIMEOUT_LOOPCOUNT=0”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ivr-1:2] Set(“SIP/310-00000001”, “INVALID_LOOPCOUNT=0”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ivr-1:3] Set(“SIP/310-00000001”, “_IVR_CONTEXT_ivr-1=”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ivr-1:4] Set(“SIP/310-00000001”, “_IVR_CONTEXT=ivr-1”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ivr-1:5] Set(“SIP/310-00000001”, “__IVR_RETVM=”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ivr-1:6] GotoIf(“SIP/310-00000001”, “0?skip”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ivr-1:7] Answer(“SIP/310-00000001”, “”) in new stack
[2013-06-28 12:33:57] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:57] – Executing [s@ivr-1:8] Wait(“SIP/310-00000001”, “1”) in new stack
[2013-06-28 12:33:58] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:58] – Executing [s@ivr-1:9] Set(“SIP/310-00000001”, “IVR_MSG=custom/MainMenu”) in new stack
[2013-06-28 12:33:58] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:58] – Executing [s@ivr-1:10] Set(“SIP/310-00000001”, “TIMEOUT(digit)=3”) in new stack
[2013-06-28 12:33:58] VERBOSE[941][C-00000001] func_timeout.c: [2013-06-28 12:33:58] – Digit timeout set to 3.000
[2013-06-28 12:33:58] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:33:58] – Executing [s@ivr-1:11] ExecIf(“SIP/310-00000001”, “1?Background(custom/MainMenu)”) in new stack
[2013-06-28 12:33:58] VERBOSE[941][C-00000001] file.c: [2013-06-28 12:33:58] – <SIP/310-00000001> Playing ‘custom/MainMenu.slin’ (language ‘en’)
[2013-06-28 12:34:00] NOTICE[898] chan_sip.c: Failed to authenticate device “District Clerk” sip:[email protected];tag=6FC05438-F2C29303 for SUBSCRIBE
[2013-06-28 12:34:00] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:34:00] == Spawn extension (ivr-1, s, 11) exited non-zero on ‘SIP/310-00000001’
[2013-06-28 12:34:00] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:34:00] – Executing [h@ivr-1:1] Hangup(“SIP/310-00000001”, “”) in new stack
[2013-06-28 12:34:00] VERBOSE[941][C-00000001] pbx.c: [2013-06-28 12:34:00] == Spawn extension (ivr-1, h, 1) exited non-zero on ‘SIP/310-00000001’

5

When dialing out on the old server, this is the log

[Jun 28 12:56:52] VERBOSE[2922] logger.c: == Using SIP RTP TOS bits 184
[Jun 28 12:56:52] VERBOSE[2922] logger.c: == Using SIP RTP CoS mark 5
[Jun 28 12:56:52] VERBOSE[2922] logger.c: == Using SIP VRTP TOS bits 136
[Jun 28 12:56:52] VERBOSE[2922] logger.c: == Using SIP VRTP CoS mark 6
[Jun 28 12:56:52] DEBUG[2881] pbx.c: FONALITY: This thread has already held the conlock, skip locking
[Jun 28 12:56:52] DEBUG[2881] pbx.c: FONALITY: This thread has already held the conlock, skip locking
[Jun 28 12:56:52] VERBOSE[2881] logger.c: == Extension Changed 111[ext-local] new state InUse for Notify User 101
[Jun 28 12:56:52] VERBOSE[2881] logger.c: == Extension Changed 111[ext-local] new state InUse for Notify User 101
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [93864350@from-unrestricted:1] Macro(“SIP/111-00000109”, “user-callerid,SKIPTTL,”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-user-callerid:1] Set(“SIP/111-00000109”, “AMPUSER=111”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-user-callerid:2] GotoIf(“SIP/111-00000109”, “0?report”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-user-callerid:3] ExecIf(“SIP/111-00000109”, “1?Set(REALCALLERIDNUM=111)”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-user-callerid:4] Set(“SIP/111-00000109”, “AMPUSER=111”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-user-callerid:5] Set(“SIP/111-00000109”, “AMPUSERCIDNAME=Director of IT”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-user-callerid:6] GotoIf(“SIP/111-00000109”, “0?report”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-user-callerid:7] Set(“SIP/111-00000109”, “AMPUSERCID=111”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-user-callerid:8] Set(“SIP/111-00000109”, "CALLERID(all)=“Director of IT” ") in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-user-callerid:9] ExecIf(“SIP/111-00000109”, “0?Set(CHANNEL(language)=)”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-user-callerid:10] GotoIf(“SIP/111-00000109”, “1?continue”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Goto (macro-user-callerid,s,19)
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-user-callerid:19] NoOp(“SIP/111-00000109”, "Using CallerID “Director of IT” ") in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [93864350@from-unrestricted:2] Set(“SIP/111-00000109”, “_NODEST=”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [93864350@from-unrestricted:3] Macro(“SIP/111-00000109”, “record-enable,111,OUT,”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-record-enable:1] GotoIf(“SIP/111-00000109”, “1?check”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Goto (macro-record-enable,s,4)
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Executing [s@macro-record-enable:4] AGI(“SIP/111-00000109”, “recordingcheck,20130628-125652,1372442212.807”) in new stack
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Jun 28 12:56:52] VERBOSE[19326] logger.c: recordingcheck,20130628-125652,1372442212.807: Outbound recording not enabled
[Jun 28 12:56:52] VERBOSE[19326] logger.c: – <SIP/111-00000109>AGI Script recordingcheck completed, returning 0
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-record-enable:5] MacroExit(“SIP/111-00000109”, “”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [93864350@from-unrestricted:4] Macro(“SIP/111-00000109”, “dialout-trunk,6,3864350,”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:1] Set(“SIP/111-00000109”, “DIAL_TRUNK=6”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:2] GosubIf(“SIP/111-00000109”, “0?sub-pincheck,s,1”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:3] GotoIf(“SIP/111-00000109”, “0?disabletrunk,1”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:4] Set(“SIP/111-00000109”, “DIAL_NUMBER=3864350”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:5] Set(“SIP/111-00000109”, “DIAL_TRUNK_OPTIONS=tr”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:6] Set(“SIP/111-00000109”, “OUTBOUND_GROUP=OUT_6”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:7] GotoIf(“SIP/111-00000109”, “0?nomax”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:8] GotoIf(“SIP/111-00000109”, “0?chanfull”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:9] GotoIf(“SIP/111-00000109”, “0?skipoutcid”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:10] Set(“SIP/111-00000109”, “DIAL_TRUNK_OPTIONS=”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:11] Macro(“SIP/111-00000109”, “outbound-callerid,6”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-outbound-callerid:1] ExecIf(“SIP/111-00000109”, “0?Set(CALLERPRES()=)”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-outbound-callerid:2] ExecIf(“SIP/111-00000109”, “0?Set(REALCALLERIDNUM=111)”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-outbound-callerid:3] GotoIf(“SIP/111-00000109”, “1?normcid”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Goto (macro-outbound-callerid,s,6)
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-outbound-callerid:6] Set(“SIP/111-00000109”, “USEROUTCID=”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-outbound-callerid:7] Set(“SIP/111-00000109”, “EMERGENCYCID=6203455501”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-outbound-callerid:8] Set(“SIP/111-00000109”, “TRUNKOUTCID=”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-outbound-callerid:9] GotoIf(“SIP/111-00000109”, “1?trunkcid”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Goto (macro-outbound-callerid,s,12)
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-outbound-callerid:12] ExecIf(“SIP/111-00000109”, “0?Set(CALLERID(all)=)”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-outbound-callerid:13] ExecIf(“SIP/111-00000109”, “0?Set(CALLERID(all)=)”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-outbound-callerid:14] ExecIf(“SIP/111-00000109”, “0?Set(CALLERPRES()=prohib_passed_screen)”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:12] ExecIf(“SIP/111-00000109”, “1?AGI(fixlocalprefix)”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
[Jun 28 12:56:53] VERBOSE[19326] logger.c: == fixlocalprefix: Dialpattern NXXXXXX matched. 3864350 -> 3864350
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – <SIP/111-00000109>AGI Script fixlocalprefix completed, returning 0
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:13] Set(“SIP/111-00000109”, “OUTNUM=3864350”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:14] Set(“SIP/111-00000109”, “custom=DAHDI/g0”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:15] ExecIf(“SIP/111-00000109”, “0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^))”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:16] Macro(“SIP/111-00000109”, “dialout-trunk-predial-hook,”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit(“SIP/111-00000109”, “”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:17] GotoIf(“SIP/111-00000109”, “0?bypass,1”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:18] GotoIf(“SIP/111-00000109”, “0?customtrunk”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Executing [s@macro-dialout-trunk:19] Dial(“SIP/111-00000109”, “DAHDI/g0/3864350,300,”) in new stack
[Jun 28 12:56:53] VERBOSE[19326] logger.c: – Called g0/3864350
[Jun 28 12:57:02] VERBOSE[19329] logger.c: – Starting simple switch on ‘DAHDI/2-1’
[Jun 28 12:57:03] DEBUG[2881] pbx.c: FONALITY: This thread has already held the conlock, skip locking
[Jun 28 12:57:03] DEBUG[2881] pbx.c: FONALITY: This thread has already held the conlock, skip locking
[Jun 28 12:57:03] VERBOSE[2881] logger.c: == Extension Changed 111[ext-local] new state Idle for Notify User 101
[Jun 28 12:57:03] VERBOSE[2881] logger.c: == Extension Changed 111[ext-local] new state Idle for Notify User 101
[Jun 28 12:57:03] VERBOSE[19326] logger.c: – Hungup ‘DAHDI/1-1’
[Jun 28 12:57:03] VERBOSE[19326] logger.c: == Spawn extension (macro-dialout-trunk, s, 19) exited non-zero on ‘SIP/111-00000109’ in macro ‘dialout-trunk’
[Jun 28 12:57:03] VERBOSE[19326] logger.c: == Spawn extension (from-unrestricted, 93864350, 4) exited non-zero on ‘SIP/111-00000109’
[Jun 28 12:57:03] VERBOSE[19326] logger.c: – Executing [h@from-unrestricted:1] System(“SIP/111-00000109”, "/var/lib/asterisk/bin/fax-process.pl --to --from [email protected] --dest “” --subject “Fax from 111 Director%20of%20IT” --attachment fax_111.pdf --type application/pdf --file ") in new stack
[Jun 28 12:57:04] VERBOSE[19326] logger.c: – Executing [h@from-unrestricted:2] Hangup(“SIP/111-00000109”, “”) in new stack
[Jun 28 12:57:04] VERBOSE[19326] logger.c: == Spawn extension (from-unrestricted, h, 2) exited non-zero on ‘SIP/111-00000109’

6

I seem to have the incoming working but haven’t been able to figure out the outgoing. It’s not hitting the IVR anymore but now I don’t hear anything after dialing. Any help would be great!

Thanks!

7

After trying a few things, I noticed that when I attempt to make a phone call from inside the organisation, I hear nothing. But as the phone call is being attempted, I tried calling into the organisation from outside and got a busy signal. Is it related? Thanks…

8

I seem to have the incoming and outgoing working at the moment. Although I’m not sure if it will remain that way. :slight_smile: However, I am still having the busy signal issue. When I have an active call, it doesn’t allow another call to come in (gives busy signal). Thoughts?

9

I’m pretty sure I didn’t change any settings and now it seems to be working. Normally, I wouldn’t care, except clearly something doesn’t seem right?? Or maybe I should just go with it?

10

In the. No working example the outbound. call was originated from trunk instead of from. Internal

11

I am still having issues getting the outbound and inbound calls to work. It seems like every time I make a change and restart the services, something breaks and it takes having to start fresh just to get it going again. So… What should the context in dahdi_channels.conf and chan_dahdi_groups.conf be? Also, what is the difference between the two. Just trying to get a better understanding. Thanks!

12

To add to my previous post… When it isn’t working, it says ‘All circuits are busy now’ when trying to place an outbound call. Also, when I open the DAHDi module in the gui, it says "DAHDi Doesn’t appear to be running. Click the ‘Restart/Reload Dahdi Button’ Below. I tried restarting the server and still nothing… Thanks!

13

I’m not sure if it’s working 100% now, but it seems to be. I will post back here if I need to. I think by using the module and setup-sangoma (and running dahdi_cfg at startup) at the same time was making things complicated. I’m still not sure what I did exactly to make it work, but we’ll go with it. After looking at the configs it seems that the system.conf in /etc/dahdi is being changed after a startup (I think). Does that make sense? Or can anyone explain why it’s being changed? Thanks for your help…

14

can anyone provide insight on this?? the system.conf is still changing when i restart asterisk and then i get the ‘all circuits are busy’ message. thoughts?

15

bump

16

help?