Unable to make outbound calls

Discussion in 'General' started by EricLomm, May 27, 2009.

  1. EricLomm

    Joined:
    May 19, 2009
    Messages:
    20
    Likes Received:
    0
    Hello,

    We are getting close to having elastix up and running in our office.

    Right now the one thing that we are still struggling with is that when we try and make any outbound call from most of our extensions the outbound calls are all being transferred to an internal extension.

    For example if I try and call my home it is going to an extension in our office.

    What would be causing this and how can we fix this?

    Thanks
     
  2. Megabyte

    Joined:
    Mar 28, 2009
    Messages:
    327
    Likes Received:
    0
    Can you give us more details,

    What type of trunks do you have for external calls?
    paste a complete call process of an external call from CLI?
    What type of extensions do you have?, sip,zap,iax, etc.
     
  3. dicko

    Joined:
    Oct 24, 2008
    Messages:
    4,099
    Likes Received:
    0
    Without knowing your "dialplan" it is hard to diagnose, there is a setting for interdigit timeout that can be too short for the slow fingered, I note you are using vitelity so I assume you are in North America,, as the NANP is "closed" dialling (1NXXNXXXXXX) If you specifically require this dialing pattern and don't use extensions starting with 1 then it becomes easier to route your calls unambiguously.
     
  4. EricLomm

    Joined:
    May 19, 2009
    Messages:
    20
    Likes Received:
    0
    Hello,

    We have two outbound trunks setup, one is through VoIPstreet and the other is through vitelity.

    All of the extensions that we are using sip.

    Below is the log file from when I tried to make an outbound call. I don't know if this is what you mean when you say to post a call process or if there is something else you mean.

    Code:
    <------------->
    [May 27 09:09:22] VERBOSE[2851] logger.c: --- (13 headers 18 lines) ---
    [May 27 09:09:22] VERBOSE[2851] logger.c: Sending to 10.0.1.90 : 5060 (no NAT)
    [May 27 09:09:22] VERBOSE[2851] logger.c: Using INVITE request as basis request - 1db933d6-a9daf55d@10.0.1.90
    [May 27 09:09:22] VERBOSE[2851] logger.c:
    <--- Reliably Transmitting (NAT) to 10.0.1.90:5060 --->
    SIP/2.0 407 Proxy Authentication Required
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-2333e709;received=10.0.1.90
    From: "Eric" <sip:23@10.0.1.20>;tag=f1394982771bfba1o0
    To: <sip:30377xxxxx@10.0.1.20>;tag=as53cddbab
    Call-ID: 1db933d6-a9daf55d@10.0.1.90
    CSeq: 101 INVITE
    User-Agent: Asterisk PBX
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
    Supported: replaces
    Proxy-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="5e7379c8"
    Content-Length: 0
    
    
    <------------>
    [May 27 09:09:22] VERBOSE[2851] logger.c: Scheduling destruction of SIP dialog '1db933d6-a9daf55d@10.0.1.90' in 32000 ms (Method: INVITE)
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found user '23'
    [May 27 09:09:22] VERBOSE[2851] logger.c:
    <--- SIP read from 10.0.1.90:5060 --->
    ACK sip:30377xxxxx@10.0.1.20 SIP/2.0
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-2333e709
    From: "Eric" <sip:23@10.0.1.20>;tag=f1394982771bfba1o0
    To: <sip:30377xxxxx@10.0.1.20>;tag=as53cddbab
    Call-ID: 1db933d6-a9daf55d@10.0.1.90
    CSeq: 101 ACK
    Max-Forwards: 70
    Contact: "Eric" <sip:23@10.0.1.90:5060>
    User-Agent: Sipura/SPA841-3.1.3(a)
    Content-Length: 0
    
    
    <------------->
    [May 27 09:09:22] VERBOSE[2851] logger.c: --- (10 headers 0 lines) ---
    [May 27 09:09:22] VERBOSE[2851] logger.c:
    <--- SIP read from 10.0.1.90:5060 --->
    INVITE sip:30377xxxxx@10.0.1.20 SIP/2.0
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-a08aa5fd
    From: "Eric" <sip:23@10.0.1.20>;tag=f1394982771bfba1o0
    To: <sip:30377xxxxx@10.0.1.20>
    Call-ID: 1db933d6-a9daf55d@10.0.1.90
    CSeq: 102 INVITE
    Max-Forwards: 70
    Proxy-Authorization: Digest username="23",realm="asterisk",nonce="5e7379c8",uri="sip:30377xxxxx@10.0.1.20",algorithm=MD5,response="fb8ebc8baa77378b159d6978384c7fc6"
    Contact: "Eric" <sip:23@10.0.1.90:5060>
    Expires: 240
    User-Agent: Sipura/SPA841-3.1.3(a)
    Content-Length: 389
    Allow: ACK, BYE, CANCEL, INFO, INVITE, NOTIFY, OPTIONS, REFER
    Content-Type: application/sdp
    
    v=0
    o=- 283836 283836 IN IP4 10.0.1.90
    s=-
    c=IN IP4 10.0.1.90
    t=0 0
    m=audio 16478 RTP/AVP 0 2 4 8 18 96 97 98 101
    a=rtpmap:0 PCMU/8000
    a=rtpmap:2 G726-32/8000
    a=rtpmap:4 G723/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:18 G729a/8000
    a=rtpmap:96 G726-40/8000
    a=rtpmap:97 G726-24/8000
    a=rtpmap:98 G726-16/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-15
    a=ptime:30
    a=sendrecv
    
    <------------->
    [May 27 09:09:22] VERBOSE[2851] logger.c: --- (14 headers 18 lines) ---
    [May 27 09:09:22] VERBOSE[2851] logger.c: Sending to 10.0.1.90 : 5060 (NAT)
    [May 27 09:09:22] VERBOSE[2851] logger.c: Using INVITE request as basis request - 1db933d6-a9daf55d@10.0.1.90
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found user '23'
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found RTP audio format 0
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found RTP audio format 2
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found RTP audio format 4
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found RTP audio format 8
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found RTP audio format 18
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found RTP audio format 96
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found RTP audio format 97
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found RTP audio format 98
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found RTP audio format 101
    [May 27 09:09:22] VERBOSE[2851] logger.c: Peer audio RTP is at port 10.0.1.90:16478
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found audio description format PCMU for ID 0
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found audio description format G726-32 for ID 2
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found audio description format G723 for ID 4
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found audio description format PCMA for ID 8
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found audio description format G729a for ID 18
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found unknown media description format G726-40 for ID 96
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found unknown media description format G726-24 for ID 97
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found unknown media description format G726-16 for ID 98
    [May 27 09:09:22] VERBOSE[2851] logger.c: Found audio description format telephone-event for ID 101
    [May 27 09:09:22] VERBOSE[2851] logger.c: Capabilities: us - 0xc (ulaw|alaw), peer - audio=0x90d (g723|ulaw|alaw|g726|g729)/video=0x0 (nothing), combined - 0xc (ulaw|alaw)
    [May 27 09:09:22] VERBOSE[2851] logger.c: Non-codec capabilities (dtmf): us - 0x1 (telephone-event), peer - 0x1 (telephone-event), combined - 0x1 (telephone-event)
    [May 27 09:09:22] VERBOSE[2851] logger.c: Peer audio RTP is at port 10.0.1.90:16478
    [May 27 09:09:22] DEBUG[2851] chan_sip.c: Call from peer '23' is 1 out of 50
    [May 27 09:09:22] VERBOSE[2851] logger.c: Looking for 30377xxxxx in from-sip-external (domain 10.0.1.20)
    [May 27 09:09:22] VERBOSE[2851] logger.c: list_route: hop: <sip:23@10.0.1.90:5060>
    [May 27 09:09:22] VERBOSE[2851] logger.c:
    <--- Transmitting (NAT) to 10.0.1.90:5060 --->
    SIP/2.0 100 Trying
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-a08aa5fd;received=10.0.1.90
    From: "Eric" <sip:23@10.0.1.20>;tag=f1394982771bfba1o0
    To: <sip:30377xxxxx@10.0.1.20>
    Call-ID: 1db933d6-a9daf55d@10.0.1.90
    CSeq: 102 INVITE
    User-Agent: Asterisk PBX
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
    Supported: replaces
    Contact: <sip:30377xxxxx@10.0.1.20>
    Content-Length: 0
    
    
    <------------>
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [30377xxxxx@from-sip-external:1] NoOp("SIP/23-b751eb60", "Received incoming SIP connection from unknown peer to 30377xxxxx") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [30377xxxxx@from-sip-external:2] Set("SIP/23-b751eb60", "DID=30377xxxxx") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [30377xxxxx@from-sip-external:3] Goto("SIP/23-b751eb60", "s|1") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Goto (from-sip-external,s,1)
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@from-sip-external:1] GotoIf("SIP/23-b751eb60", "1?from-trunk|30377xxxxx|1") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Goto (from-trunk,30377xxxxx,1)
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [30377xxxxx@from-trunk:1] NoOp("SIP/23-b751eb60", "Catch-All DID Match - Found 30377xxxxx - You probably want a DID for this.") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [30377xxxxx@from-trunk:2] Goto("SIP/23-b751eb60", "ext-did|s|1") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Goto (ext-did,s,1)
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@ext-did:1] Set("SIP/23-b751eb60", "__FROM_DID=s") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@ext-did:2] Gosub("SIP/23-b751eb60", "app-blacklist-check|s|1") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@app-blacklist-check:1] LookupBlacklist("SIP/23-b751eb60", "") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@app-blacklist-check:2] GotoIf("SIP/23-b751eb60", "0?blacklisted") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@app-blacklist-check:3] Return("SIP/23-b751eb60", "") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@ext-did:3] ExecIf("SIP/23-b751eb60", "0 |Set|CALLERID(name)=23") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@ext-did:4] Set("SIP/23-b751eb60", "FAX_RX=") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@ext-did:5] Set("SIP/23-b751eb60", "__CALLINGPRES_SV=allowed_not_screened") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@ext-did:6] SetCallerPres("SIP/23-b751eb60", "allowed_not_screened") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@ext-did:7] Goto("SIP/23-b751eb60", "from-did-direct|180|1") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Goto (from-did-direct,180,1)
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [180@from-did-direct:1] Macro("SIP/23-b751eb60", "exten-vm|180|180") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:1] Macro("SIP/23-b751eb60", "user-callerid") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/23-b751eb60", "AMPUSER=23") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/23-b751eb60", "0?report") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/23-b751eb60", "1|Set|REALCALLERIDNUM=23") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: ExecIf
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/23-b751eb60", "AMPUSER=23") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/23-b751eb60", "AMPUSERCIDNAME=Eric") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/23-b751eb60", "0?report") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/23-b751eb60", "AMPUSERCID=23") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/23-b751eb60", "CALLERID(all)="Eric" <23>") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:9] Set("SIP/23-b751eb60", "REALCALLERIDNUM=23") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:10] ExecIf("SIP/23-b751eb60", "0|Set|CHANNEL(language)=") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: ExecIf
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:11] GotoIf("SIP/23-b751eb60", "0?continue") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/23-b751eb60", "__TTL=64") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:13] GotoIf("SIP/23-b751eb60", "1?continue") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Goto (macro-user-callerid,s,20)
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:20] NoOp("SIP/23-b751eb60", "Using CallerID "Eric" <23>") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Noop
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Macro
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:2] Set("SIP/23-b751eb60", "RingGroupMethod=none") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:3] Set("SIP/23-b751eb60", "VMBOX=180") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:4] Set("SIP/23-b751eb60", "EXTTOCALL=180") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] DEBUG[13640] func_db.c: DB: CFU/180 not found in database.
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:5] Set("SIP/23-b751eb60", "CFUEXT=") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] DEBUG[13640] func_db.c: DB: CFB/180 not found in database.
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:6] Set("SIP/23-b751eb60", "CFBEXT=") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:7] Set("SIP/23-b751eb60", "RT=15") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:8] Macro("SIP/23-b751eb60", "record-enable|180|IN") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/23-b751eb60", "1?check") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Goto (macro-record-enable,s,4)
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/23-b751eb60", "recordingcheck|20090527-090922|1243436962.169") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
    [May 27 09:09:22] VERBOSE[13640] logger.c: recordingcheck|20090527-090922|1243436962.169: Inbound recording not enabled
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- AGI Script recordingcheck completed, returning 0
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: AGI
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("SIP/23-b751eb60", "") in new stack
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: Macro
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:9] Macro("SIP/23-b751eb60", "dial|15|tr|180") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-dial:1] GotoIf("SIP/23-b751eb60", "1?dial") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Goto (macro-dial,s,3)
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-dial:3] AGI("SIP/23-b751eb60", "dialparties.agi") in new stack
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
    [May 27 09:09:22] VERBOSE[13640] logger.c: dialparties.agi: Starting New Dialparties.agi
    [May 27 09:09:22] VERBOSE[13643] logger.c: == Parsing '/etc/asterisk/manager.conf': [May 27 09:09:22] VERBOSE[13643] logger.c: Found
    [May 27 09:09:22] VERBOSE[13643] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [May 27 09:09:22] VERBOSE[13643] logger.c: Found
    [May 27 09:09:22] VERBOSE[13643] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [May 27 09:09:22] VERBOSE[13643] logger.c: Found
    [May 27 09:09:22] WARNING[13643] config.c: Unknown directive '#permit=192.168.1.0/255.255.255.0' at line 18 of /etc/asterisk/manager_custom.conf
    [May 27 09:09:22] VERBOSE[13643] logger.c: == Manager 'admin' logged on from 127.0.0.1
    [May 27 09:09:22] VERBOSE[13640] logger.c: dialparties.agi: Caller ID name is 'Eric' number is '23'
    [May 27 09:09:22] VERBOSE[13640] logger.c: dialparties.agi: Methodology of ring is 'none'
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- dialparties.agi: Added extension 180 to extension map
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- dialparties.agi: Extension 180 cf is disabled
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- dialparties.agi: Extension 180 do not disturb is disabled
    [May 27 09:09:22] VERBOSE[13640] logger.c: dialparties.agi: Extension 180 has ExtensionState: 0
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- dialparties.agi: Checking CW and CFB status for extension 180
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- dialparties.agi: dbset CALLTRACE/180 to 23
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- dialparties.agi: Filtered ARG3: 180
    [May 27 09:09:22] VERBOSE[13643] logger.c: == Manager 'admin' logged off from 127.0.0.1
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- AGI Script dialparties.agi completed, returning 0
    [May 27 09:09:22] DEBUG[13640] app_macro.c: Executed application: AGI
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Executing [s@macro-dial:7] Dial("SIP/23-b751eb60", "SIP/180|15|tr") in new stack
    [May 27 09:09:22] NOTICE[13640] app_dial.c: Hey! chan SIP/23-b751eb60's context='macro-dial', and exten='s'
    [May 27 09:09:22] DEBUG[13640] chan_sip.c: Call to peer '180' is 1 out of 50
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- Called 180
    [May 27 09:09:22] VERBOSE[13640] logger.c:
    <--- Transmitting (NAT) to 10.0.1.90:5060 --->
    SIP/2.0 180 Ringing
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-a08aa5fd;received=10.0.1.90
    From: "Eric" <sip:23@10.0.1.20>;tag=f1394982771bfba1o0
    To: <sip:30377xxxxx@10.0.1.20>;tag=as3aa64f86
    Call-ID: 1db933d6-a9daf55d@10.0.1.90
    CSeq: 102 INVITE
    User-Agent: Asterisk PBX
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
    Supported: replaces
    Contact: <sip:30377xxxxx@10.0.1.20>
    Content-Length: 0
    
    
    <------------>
    [May 27 09:09:22] VERBOSE[13640] logger.c: -- SIP/180-b796adf8 is ringing
    [May 27 09:09:25] VERBOSE[2851] logger.c: -- Got SIP response 480 "Temporarily Unavailable" back from 10.0.1.45
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- SIP/180-b796adf8 is circuit-busy
    [May 27 09:09:25] DEBUG[13640] chan_sip.c: Call to peer '180' removed from call limit 50
    [May 27 09:09:25] VERBOSE[13640] logger.c: == Everyone is busy/congested at this time (1:0/1/0)
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Dial
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-dial:8] Set("SIP/23-b751eb60", "DIALSTATUS=CONGESTION") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-dial:9] GosubIf("SIP/23-b751eb60", "0?CONGESTION|1") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GosubIf
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Macro
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:10] GotoIf("SIP/23-b751eb60", "0?exit|return") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:11] Set("SIP/23-b751eb60", "SV_DIALSTATUS=CONGESTION") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:12] GosubIf("SIP/23-b751eb60", "0?docfu|1") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GosubIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:13] GosubIf("SIP/23-b751eb60", "0?docfb|1") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GosubIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:14] Set("SIP/23-b751eb60", "DIALSTATUS=CONGESTION") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:15] NoOp("SIP/23-b751eb60", "Voicemail is 180") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: NoOp
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:16] GotoIf("SIP/23-b751eb60", "0?s-CONGESTION|1") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:17] NoOp("SIP/23-b751eb60", "Sending to Voicemail box 180") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: NoOp
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-exten-vm:18] Macro("SIP/23-b751eb60", "vm|180|CONGESTION|") in new stack
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-vm:1] Macro("SIP/23-b751eb60", "user-callerid|SKIPTTL") in new stack
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/23-b751eb60", "AMPUSER=23") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/23-b751eb60", "0?report") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/23-b751eb60", "0|Set|REALCALLERIDNUM=23") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: ExecIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/23-b751eb60", "AMPUSER=23") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/23-b751eb60", "AMPUSERCIDNAME=Eric") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/23-b751eb60", "0?report") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/23-b751eb60", "AMPUSERCID=23") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/23-b751eb60", "CALLERID(all)="Eric" <23>") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:9] Set("SIP/23-b751eb60", "REALCALLERIDNUM=23") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:10] ExecIf("SIP/23-b751eb60", "0|Set|CHANNEL(language)=") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: ExecIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:11] GotoIf("SIP/23-b751eb60", "1?continue") in new stack
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Goto (macro-user-callerid,s,20)
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-user-callerid:20] NoOp("SIP/23-b751eb60", "Using CallerID "Eric" <23>") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Noop
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Macro
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-vm:2] Set("SIP/23-b751eb60", "VMGAIN=""") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] WARNING[13640] func_db.c: DB requires an argument, DB(<family>/<key>)
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-vm:3] GotoIf("SIP/23-b751eb60", "1?vmx|1") in new stack
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Goto (macro-vm,vmx,1)
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [vmx@macro-vm:1] GotoIf("SIP/23-b751eb60", "0?s-CONGESTION|1") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [vmx@macro-vm:2] Set("SIP/23-b751eb60", "MODE=unavail") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [vmx@macro-vm:3] GotoIf("SIP/23-b751eb60", "1?notdirect") in new stack
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Goto (macro-vm,vmx,5)
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:25] DEBUG[13640] func_db.c: DB: AMPUSER/180/vmx/unavail/state not found in database.
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [vmx@macro-vm:5] NoOp("SIP/23-b751eb60", "Checking if ext 180 is enabled: ") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Noop
    [May 27 09:09:25] DEBUG[13640] func_db.c: DB: AMPUSER/180/vmx/unavail/state not found in database.
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [vmx@macro-vm:6] GotoIf("SIP/23-b751eb60", "1?s-CONGESTION|1") in new stack
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Goto (macro-vm,s-CONGESTION,1)
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s-CONGESTION@macro-vm:1] Macro("SIP/23-b751eb60", "get-vmcontext|180") in new stack
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-get-vmcontext:1] Set("SIP/23-b751eb60", "VMCONTEXT=default") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Set
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/23-b751eb60", "0?200:300") in new stack
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Goto (macro-get-vmcontext,s,300)
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: GotoIf
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/23-b751eb60", "") in new stack
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: NoOp
    [May 27 09:09:25] DEBUG[13640] app_macro.c: Executed application: Macro
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- Executing [s-CONGESTION@macro-vm:2] VoiceMail("SIP/23-b751eb60", "180@default|u") in new stack
    [May 27 09:09:25] VERBOSE[13640] logger.c: Audio is at 10.0.1.20 port 18276
    [May 27 09:09:25] VERBOSE[13640] logger.c: Adding codec 0x4 (ulaw) to SDP
    [May 27 09:09:25] VERBOSE[13640] logger.c: Adding codec 0x8 (alaw) to SDP
    [May 27 09:09:25] VERBOSE[13640] logger.c: Adding non-codec 0x1 (telephone-event) to SDP
    [May 27 09:09:25] VERBOSE[13640] logger.c:
    <--- Reliably Transmitting (NAT) to 10.0.1.90:5060 --->
    SIP/2.0 200 OK
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-a08aa5fd;received=10.0.1.90
    From: "Eric" <sip:23@10.0.1.20>;tag=f1394982771bfba1o0
    To: <sip:30377xxxxx@10.0.1.20>;tag=as3aa64f86
    Call-ID: 1db933d6-a9daf55d@10.0.1.90
    CSeq: 102 INVITE
    User-Agent: Asterisk PBX
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
    Supported: replaces
    Contact: <sip:30377xxxxx@10.0.1.20>
    Content-Type: application/sdp
    Content-Length: 256
    
    v=0
    o=root 2689 2689 IN IP4 10.0.1.20
    s=session
    c=IN IP4 10.0.1.20
    t=0 0
    m=audio 18276 RTP/AVP 0 8 101
    a=rtpmap:0 PCMU/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-16
    a=silenceSupp:off - - - -
    a=ptime:20
    a=sendrecv
    
    <------------>
    [May 27 09:09:25] VERBOSE[13640] logger.c: -- <SIP/23-b751eb60> Playing 'vm-theperson' (language 'en')
    [May 27 09:09:25] VERBOSE[2851] logger.c:
    <--- SIP read from 10.0.1.90:5060 --->
    ACK sip:30377xxxxx@10.0.1.20 SIP/2.0
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-960c527a
    From: "Eric" <sip:23@10.0.1.20>;tag=f1394982771bfba1o0
    To: <sip:30377xxxxx@10.0.1.20>;tag=as3aa64f86
    Call-ID: 1db933d6-a9daf55d@10.0.1.90
    CSeq: 102 ACK
    Max-Forwards: 70
    Proxy-Authorization: Digest username="23",realm="asterisk",nonce="5e7379c8",uri="sip:30377xxxxx@10.0.1.20",algorithm=MD5,response="4606f2467fe765612b9429274eceb728"
    Contact: "Eric" <sip:23@10.0.1.90:5060>
    User-Agent: Sipura/SPA841-3.1.3(a)
    Content-Length: 0
    
    
    <------------->
    [May 27 09:09:25] VERBOSE[2851] logger.c: --- (11 headers 0 lines) ---
    [May 27 09:09:25] VERBOSE[2851] logger.c:
    <--- SIP read from 10.0.1.90:5060 --->
    NOTIFY sip:10.0.1.20 SIP/2.0
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-4b5fc823
    From: "Eric" <sip:23@10.0.1.20>;tag=a919076f1dcebb85o0
    To: <sip:10.0.1.20>
    Call-ID: 4d8d3c2f-3f7a0dc5@10.0.1.90
    CSeq: 189 NOTIFY
    Max-Forwards: 70
    Event: keep-alive
    User-Agent: Sipura/SPA841-3.1.3(a)
    Content-Length: 0
    
    
    <------------->
    [May 27 09:09:25] VERBOSE[2851] logger.c: --- (10 headers 0 lines) ---
    [May 27 09:09:25] VERBOSE[2851] logger.c: Sending to 10.0.1.90 : 5060 (no NAT)
    [May 27 09:09:25] VERBOSE[2851] logger.c:
    <--- Transmitting (no NAT) to 10.0.1.90:5060 --->
    SIP/2.0 489 Bad event
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-4b5fc823;received=10.0.1.90
    From: "Eric" <sip:23@10.0.1.20>;tag=a919076f1dcebb85o0
    To: <sip:10.0.1.20>;tag=as2c58265b
    Call-ID: 4d8d3c2f-3f7a0dc5@10.0.1.90
    CSeq: 189 NOTIFY
    User-Agent: Asterisk PBX
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
    Supported: replaces
    Content-Length: 0
    
    
    <------------>
    [May 27 09:09:26] VERBOSE[13640] logger.c: -- <SIP/23-b751eb60> Playing 'digits/1' (language 'en')
    [May 27 09:09:27] VERBOSE[13640] logger.c: -- <SIP/23-b751eb60> Playing 'digits/8' (language 'en')
    [May 27 09:09:28] VERBOSE[13640] logger.c: -- <SIP/23-b751eb60> Playing 'digits/0' (language 'en')
    [May 27 09:09:28] VERBOSE[13640] logger.c: -- <SIP/23-b751eb60> Playing 'vm-isunavail' (language 'en')
    [May 27 09:09:30] VERBOSE[2851] logger.c:
    <--- SIP read from 10.0.1.90:5060 --->
    BYE sip:30377xxxxx@10.0.1.20 SIP/2.0
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-b6e2286a
    From: "Eric" <sip:23@10.0.1.20>;tag=f1394982771bfba1o0
    To: <sip:30377xxxxx@10.0.1.20>;tag=as3aa64f86
    Call-ID: 1db933d6-a9daf55d@10.0.1.90
    CSeq: 103 BYE
    Max-Forwards: 70
    Proxy-Authorization: Digest username="23",realm="asterisk",nonce="5e7379c8",uri="sip:30377xxxxx@10.0.1.20",algorithm=MD5,response="3f8d08c758dd14472755f6f687067f6b"
    User-Agent: Sipura/SPA841-3.1.3(a)
    Content-Length: 0
    
    
    <------------->
    [May 27 09:09:30] VERBOSE[2851] logger.c: --- (10 headers 0 lines) ---
    [May 27 09:09:30] VERBOSE[2851] logger.c: Sending to 10.0.1.90 : 5060 (NAT)
    [May 27 09:09:30] VERBOSE[2851] logger.c:
    <--- Transmitting (NAT) to 10.0.1.90:5060 --->
    SIP/2.0 200 OK
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-b6e2286a;received=10.0.1.90
    From: "Eric" <sip:23@10.0.1.20>;tag=f1394982771bfba1o0
    To: <sip:30377xxxxx@10.0.1.20>;tag=as3aa64f86
    Call-ID: 1db933d6-a9daf55d@10.0.1.90
    CSeq: 103 BYE
    User-Agent: Asterisk PBX
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
    Supported: replaces
    Content-Length: 0
    
    
    <------------>
    [May 27 09:09:30] VERBOSE[13640] logger.c: == Spawn extension (macro-vm, s-CONGESTION, 2) exited non-zero on 'SIP/23-b751eb60' in macro 'vm'
    [May 27 09:09:30] VERBOSE[13640] logger.c: == Spawn extension (macro-exten-vm, s, 18) exited non-zero on 'SIP/23-b751eb60' in macro 'exten-vm'
    [May 27 09:09:30] VERBOSE[13640] logger.c: == Spawn extension (from-did-direct, 180, 1) exited non-zero on 'SIP/23-b751eb60'
    [May 27 09:09:30] DEBUG[13640] chan_sip.c: Call from peer '23' removed from call limit 50
    [May 27 09:09:30] VERBOSE[2851] logger.c: Really destroying SIP dialog '1db933d6-a9daf55d@10.0.1.90' Method: BYE
    [May 27 09:09:31] NOTICE[2851] chan_sip.c: -- Re-registration for 360andy@inbound2.vitelity.net
    [May 27 09:09:31] DEBUG[2851] chan_sip.c: >>> Re-using Auth data for 360andy@inbound2.vitelity.net
    [May 27 09:09:31] NOTICE[2851] chan_sip.c: Outbound Registration: Expiry for inbound2.vitelity.net is 60 sec (Scheduling reregistration in 45 s)
    [May 27 09:09:40] VERBOSE[2851] logger.c:
    <--- SIP read from 10.0.1.90:5060 --->
    NOTIFY sip:10.0.1.20 SIP/2.0
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-4eda70d1
    From: "Eric" <sip:23@10.0.1.20>;tag=a919076f1dcebb85o0
    To: <sip:10.0.1.20>
    Call-ID: 4d8d3c2f-3f7a0dc5@10.0.1.90
    CSeq: 190 NOTIFY
    Max-Forwards: 70
    Event: keep-alive
    User-Agent: Sipura/SPA841-3.1.3(a)
    Content-Length: 0
    
    
    <------------->
    [May 27 09:09:40] VERBOSE[2851] logger.c: --- (10 headers 0 lines) ---
    [May 27 09:09:40] VERBOSE[2851] logger.c: Sending to 10.0.1.90 : 5060 (no NAT)
    [May 27 09:09:40] VERBOSE[2851] logger.c:
    <--- Transmitting (no NAT) to 10.0.1.90:5060 --->
    SIP/2.0 489 Bad event
    Via: SIP/2.0/UDP 10.0.1.90:5060;branch=z9hG4bK-4eda70d1;received=10.0.1.90
    From: "Eric" <sip:23@10.0.1.20>;tag=a919076f1dcebb85o0
    To: <sip:10.0.1.20>;tag=as725abc0e
    Call-ID: 4d8d3c2f-3f7a0dc5@10.0.1.90
    CSeq: 190 NOTIFY
    User-Agent: Asterisk PBX
    Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
    Supported: replaces
    Content-Length: 0
    
    
    <------------>
    [May 27 09:09:41] VERBOSE[13646] logger.c: == Parsing '/etc/asterisk/manager.conf': [May 27 09:09:41] VERBOSE[13646] logger.c: Found
    [May 27 09:09:41] VERBOSE[13646] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [May 27 09:09:41] VERBOSE[13646] logger.c: Found
    [May 27 09:09:41] VERBOSE[13646] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [May 27 09:09:41] VERBOSE[13646] logger.c: Found
    [May 27 09:09:41] WARNING[13646] config.c: Unknown directive '#permit=192.168.1.0/255.255.255.0' at line 18 of /etc/asterisk/manager_custom.conf
    [May 27 09:09:41] VERBOSE[13646] logger.c: == Manager 'admin' logged on from 127.0.0.1
    [May 27 09:09:41] VERBOSE[13646] logger.c: == Manager 'admin' logged off from 127.0.0.1
    [May 27 09:09:42] VERBOSE[13648] logger.c: == Parsing '/etc/asterisk/manager.conf': [May 27 09:09:42] VERBOSE[13648] logger.c: Found
    [May 27 09:09:42] VERBOSE[13648] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [May 27 09:09:42] VERBOSE[13648] logger.c: Found
    [May 27 09:09:42] VERBOSE[13648] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [May 27 09:09:42] VERBOSE[13648] logger.c: Found
    [May 27 09:09:42] WARNING[13648] config.c: Unknown directive '#permit=192.168.1.0/255.255.255.0' at line 18 of /etc/asterisk/manager_custom.conf
    [May 27 09:09:42] VERBOSE[13648] logger.c: == Manager 'admin' logged on from 127.0.0.1 
     
  5. Megabyte

    Joined:
    Mar 28, 2009
    Messages:
    327
    Likes Received:
    0
    Paste your dialplan, and when you make a call they appear some information in cli thats what i mean. paste that information too.
     
  6. dicko

    Joined:
    Oct 24, 2008
    Messages:
    4,099
    Likes Received:
    0
    verbosity 3 and no sip debug makes it more concise, but I would check your outbound routes and dial patterns in the routes and trunks as the call to 3XXNXXXXXX was attempted through whatever is at 10.0.1.20 it probably should have tried to go to one of your providers, it ended up trying to go to extension 180 (do you recognize that etxension ? ) Also check the contexts of your trunks and extensions.
     
  7. EricLomm

    Joined:
    May 19, 2009
    Messages:
    20
    Likes Received:
    0
    This might point out stuff that I still have yet to learn, and I do still have stuff that I need to learn, in the CLI what commands would I enter to the call information?

    Also, where would I find the dialplan that you are asking for and how would it cause certain phones to be able to dial out while others cannot.
     
  8. EricLomm

    Joined:
    May 19, 2009
    Messages:
    20
    Likes Received:
    0
    Here are the dial patterns that we have setup on the outbound route.

    Code:
    1800NXXXXXX
    1866NXXXXXX
    1877NXXXXXX
    1888NXXXXXX
    1NXXNXXXXXX
    NXXNXXXXXX
    NXXXXXX
    
    10.0.1.20 is the elastix server and extension 180 is the extension that all of the outbound calls we are trying to make calls from are going to. When I look at the context of extension 180 it has a context of 'from-internal' extension 23 has a null or blank context.
     
  9. dicko

    Joined:
    Oct 24, 2008
    Messages:
    4,099
    Likes Received:
    0
    "Elastix Without Tears" available on the home page is in my opinion "required reading" before attempting to deploy an Elastix System.
    HTTP://voip-info.org
    will give you all the commands you need to go further.
    The underlying problem is that you removed the context from Ext. 23 It should be from-internal or it will have no access to the trunks, as will any other extension in the "default" context.

    Unless you rewrite your
    NXXXXXX
    rule, I doubt that your carriers will complete these calls (you need to add your area code, Instructions to be found in the aforementioned document.)
     
  10. EricLomm

    Joined:
    May 19, 2009
    Messages:
    20
    Likes Received:
    0
    I know that my boss who has done most of the deployment of our elastix box has downloaded and read "Elastix Without Tears" He has mostly asked me to get involved with some issues he could not figure out.

    We are migrating from an existing Trixbox installation.
     
  11. dicko

    Joined:
    Oct 24, 2008
    Messages:
    4,099
    Likes Received:
    0
    >He has mostly asked me to get involved with some issues he could not figure out.
    :) apparently so!, when you otherwise say "we" I assume you mean "(s)he". I hope your reading will be more productive.

    Check your inbound routes for a catchall to handle unresolvable inbound calls (or keep bugging poor old 180).
    I believe in colorado you are used to 10 digit dialing, if I'm correct:-

    800NXXXXXX
    866NXXXXXX
    877NXXXXXX
    888NXXXXXX
    NXXNXXXXXX
    NXXXXXX
    011.

    should be in the outbound route with is sent to your two trunks in the preferred order

    and 303+NXXXXXX
    in your trunks
    should largely match your "traditional" dialing pattern (apart from operator calls)
     
  12. EricLomm

    Joined:
    May 19, 2009
    Messages:
    20
    Likes Received:
    0
    Yes, when I say we I am including him. Adding the context to the phones that did not have them took care of those issues. The dialing patterns that we have setup are the same dialing that were working on our old Trixbox system and now that we have the context setup in all the extensions our outbound calls are going through correctly.
     
  13. dicko

    Joined:
    Oct 24, 2008
    Messages:
    4,099
    Likes Received:
    0
    You're welcome.
     

Share This Page