Asterisk to 3CX bridging

npereira · August 8, 2014, 2:10pm

Hello all,

I’m trying to configure Asterisk, which has users with extensions 2xxx to register to 3CX.

I have in the sip.conf:

[code][3cx-datacenter]
type=peer
username=10000
fromuser=10000
secret=
host=10.64.16.10
context=from_3cx_datacenter
port=5060
dmfmode=rfc2833
canreinvite=no
nat=never
disallow=all
allow=ulaw
qualify=yes

register => 10000:<pass>@10.64.16.10:5060/10000

[/code]

My problem is, when I do a sip show registry, i see nothing registered.

Im still pretty new to asterisk…

I basically want asterisk to register to 3CX and accept calls comming in from 3cx, both extension calls and PSTN bound calls as asterisk has the PRI on it.

npereira · August 8, 2014, 2:21pm

ok, some more info, asterisk when receiving the call from 3CX shows this:

[code]Sending to 10.64.16.10:5060 (no NAT)
Using INVITE request as basis request - YjEwMDVhMjI4MTVkOTdiODQxZGJmNjhkYTI3OTU1ZjE.
Found peer ‘3cx-datacenter’ for ‘10000’ from 10.64.16.10:5060
[Aug 8 10:15:59] WARNING[32734]: chan_sip.c:13904 check_auth: username mismatch, have <3cx-datacenter>, digest has <10000>
[Aug 8 10:15:59] NOTICE[32734]: chan_sip.c:21955 handle_request_invite: Failed to authenticate device "Nelson Pereira"sip:10000@172.20.32.10:5060;tag=c8721e5e

<— Reliably Transmitting (no NAT) to 10.64.16.10:5060 —>
SIP/2.0 403 Forbidden
Via: SIP/2.0/UDP 10.64.16.10:5060;branch=z9hG4bK-d8754z-d829b0678048b80e-1—d8754z-;received=10.64.16.10;rport=5060
From: "Nelson Pereira"sip:10000@172.20.32.10:5060;tag=c8721e5e
To: sip:2800@172.20.32.10:5060;tag=as64bca2fa
Call-ID: YjEwMDVhMjI4MTVkOTdiODQxZGJmNjhkYTI3OTU1ZjE.
CSeq: 2 INVITE
Server: Auriga Asterisk Main
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0[/code]

npereira · August 8, 2014, 3:02pm

Making progress,

After googling a litle, I found a post on another site that stated he was having the same problem as me for inbound.

by adding insecure=invite,port in the peer in sip.conf, it’s working now. Although, I still wish to know how to do it without that option, if it’s possible.

david55 · August 8, 2014, 5:54pm

Why are you using insecure=port.

Please don’t use options whose very name indicates they reduce security, unless you know what they mean. You probably only needed insecure=invite, and nowadays, you can use remotesecret, instead.

juniorloureiro · October 1, 2014, 2:42am

Hello, I’m trying to bridge 3cx with Asterisk too.
Recently I bought a e1 service from Vivo (brazil) and I had to link it to a Asterisk via TE110P.
It worked fine with asterisk, but I already use 3cx here in my office.
So I connected E1 to Asterisk and then to 3CX.
I could dial from 3CX to e1 but i couldn’t receive a call from e1 to 3cx.

Sip_general_additional.conf

;--------------------------------------------------------------------------------;
; Do NOT edit this file as it is auto-generated by FreePBX. All modifications to ;
; this file must be done via the web gui. There are alternative files to make    ;
; custom modifications, details at: http://freepbx.org/configuration_files       ;
;--------------------------------------------------------------------------------;
;

faxdetect=yes
vmexten=*97
context=from-sip-external
callerid=Unknown
notifyringing=yes
notifyhold=yes
tos_sip=cs3
tos_audio=ef
tos_video=af41
alwaysauthreject=yes
useragent=FPBX-2.8.1(1.8.20.0)
disallow=all
allow=ulaw
allow=alaw
allow=gsm

Trunk name: 10019 (the brigde to 3cx)
outgoing settings
Trunk name: 10019
Peer details:

type=peer&friend disallow=all allow=ulaw context=from-internal secret=wxjk8p6 nat=no insecure=very,invite host=192.168.2.1:5060 username=10019 fromdomain=192.168.2.1:5060 fromuser=10019 dtmfmode=auto

Incoming settings
User context: in9

type=user
disallow=all
allow=ulaw
context=from-trunk
secret=wxjk8p6
nat=no
insecure=very,invite
host=192.168.2.1:5060
username=10019
fromdomain=192.168.2.1:5060
fromuser=10019
dtmfmode=auto

Register string:

10019:wxjk8p6@192.168.2.1:5060/10019

Inbound Route is any DID / any CID to Trunks -> asterisk(SIP)

Outgoing Route is all patterns -> Zap Channel (g0)

Debugg from CLI

BM-server1*CLI> core set debug 9
Core debug was 5 and is now 9
BM-server1*CLI> core set verbose 9
Verbosity was 5 and is now 9
New MFC/R2 call detected on chan 4.
MFC/R2 call offered on chan 4. ANI = 15974049144, DNIS = 1313, Category = National Subscriber
MFC/R2 call has been accepted on backward channel 4
    -- Executing [1313@from-pstn:1] NoOp("DAHDI/4-1", "Catch-All DID Match - Found 1313 - You probably want a DID for this.") in new stack
    -- Executing [1313@from-pstn:2] Goto("DAHDI/4-1", "ext-did,s,1") in new stack
    -- Goto (ext-did,s,1)
    -- Executing [s@ext-did:1] Set("DAHDI/4-1", "__FROM_DID=s") in new stack
    -- Executing [s@ext-did:2] Gosub("DAHDI/4-1", "app-blacklist-check,s,1") in new stack
    -- Executing [s@app-blacklist-check:1] GotoIf("DAHDI/4-1", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("DAHDI/4-1", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("DAHDI/4-1", "") in new stack
    -- Executing [s@ext-did:3] ExecIf("DAHDI/4-1", "0 ?Set(CALLERID(name)=15974049144)") in new stack
    -- Executing [s@ext-did:4] Ringing("DAHDI/4-1", "") in new stack
    -- Executing [s@ext-did:5] Set("DAHDI/4-1", "__CALLINGPRES_SV=allowed_not_screened") in new stack
    -- Executing [s@ext-did:6] Set("DAHDI/4-1", "CALLERPRES()=allowed_not_screened") in new stack
    -- Executing [s@ext-did:7] Goto("DAHDI/4-1", "ext-trunk,3,1") in new stack
    -- Goto (ext-trunk,3,1)
    -- Executing [3@ext-trunk:1] Set("DAHDI/4-1", "TDIAL_STRING=SIP/10019") in new stack
    -- Executing [3@ext-trunk:2] Set("DAHDI/4-1", "DIAL_TRUNK=3") in new stack
    -- Executing [3@ext-trunk:3] Goto("DAHDI/4-1", "ext-trunk,tdial,1") in new stack
    -- Goto (ext-trunk,tdial,1)
    -- Executing [tdial@ext-trunk:1] Set("DAHDI/4-1", "OUTBOUND_GROUP=OUT_3") in new stack
    -- Executing [tdial@ext-trunk:2] GotoIf("DAHDI/4-1", "0?nomax") in new stack
    -- Executing [tdial@ext-trunk:3] GotoIf("DAHDI/4-1", "0?hangit") in new stack
    -- Executing [tdial@ext-trunk:4] ExecIf("DAHDI/4-1", "1?Set(CALLERPRES()=allowed_not_screened)") in new stack
    -- Executing [tdial@ext-trunk:5] Set("DAHDI/4-1", "DIAL_NUMBER=s") in new stack
    -- Executing [tdial@ext-trunk:6] GosubIf("DAHDI/4-1", "1?sub-flp-3,s,1") in new stack
    -- Executing [s@sub-flp-3:1] ExecIf("DAHDI/4-1", "0?Return()") in new stack
    -- Executing [s@sub-flp-3:2] Return("DAHDI/4-1", "") in new stack
    -- Executing [tdial@ext-trunk:7] Set("DAHDI/4-1", "OUTNUM=s") in new stack
    -- Executing [tdial@ext-trunk:8] Dial("DAHDI/4-1", "SIP/10019/s,300,") in new stack
  == Everyone is busy/congested at this time (1:0/0/1)
    -- Executing [tdial@ext-trunk:9] Set("DAHDI/4-1", "CALLERID(number)=15974049144") in new stack
    -- Executing [tdial@ext-trunk:10] Set("DAHDI/4-1", "CALLERID(name)=15974049144") in new stack
    -- Executing [tdial@ext-trunk:11] Hangup("DAHDI/4-1", "") in new stack
  == Spawn extension (ext-trunk, tdial, 11) exited non-zero on 'DAHDI/4-1'
    -- Hungup 'DAHDI/4-1'
MFC/R2 call end on channel 4
BM-server1*CLI>

Debug from /var/log/asterisk/full

[root@BM-server1 asterisk]# tail -f /var/log/asterisk/full
[Sep 30 23:38:31] DEBUG[350] netsock2.c: ...host '192.168.2.84' and port ''.
[Sep 30 23:38:31] DEBUG[350] netsock2.c: Splitting '192.168.2.84' into...
[Sep 30 23:38:31] DEBUG[350] netsock2.c: ...host '192.168.2.84' and port ''.
[Sep 30 23:38:31] DEBUG[350] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Sep 30 23:38:31] DEBUG[350] netsock2.c: Splitting '192.168.2.86:40715' into...
[Sep 30 23:38:31] DEBUG[350] netsock2.c: ...host '192.168.2.86' and port '40715'.
[Sep 30 23:38:31] DEBUG[350] netsock2.c: Splitting '192.168.2.84' into...
[Sep 30 23:38:31] DEBUG[350] netsock2.c: ...host '192.168.2.84' and port ''.
[Sep 30 23:38:31] DEBUG[350] chan_sip.c: Trying to put 'SIP/2.0 403' onto UDP socket destined for 192.168.2.86:40715
[Sep 30 23:38:31] NOTICE[350] chan_sip.c: Registration from '<sip:23@192.168.2.84>' failed for '192.168.2.86:40715' - No matching peer found
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - Bits changed from 0x08 to 0x00
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - CAS Rx << [SEIZE] 0x00
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - Initialized R2 MF detector
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - CAS Tx >> [SEIZE ACK] 0x0C
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - CAS Raw Tx >> 0x0D
[Sep 30 23:38:50] VERBOSE[335] chan_dahdi.c: New MFC/R2 call detected on chan 5.
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [ON]
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - Getting DNIS digit 1
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - DNIS so far: 1, expected length: 4
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - Requesting next DNIS with signal 0x31.
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 1 [ON]
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [OFF]
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 1 [OFF]
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 3 [ON]
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - Getting DNIS digit 3
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - DNIS so far: 13, expected length: 4
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - Requesting next DNIS with signal 0x31.
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 1 [ON]
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 3 [OFF]
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 1 [OFF]
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [ON]
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - Getting DNIS digit 1
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - DNIS so far: 131, expected length: 4
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - Requesting next DNIS with signal 0x31.
[Sep 30 23:38:50] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 1 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 1 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 3 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - Getting DNIS digit 3
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - DNIS so far: 1313, expected length: 4
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - Done getting DNIS!
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 3 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - Getting ANI digit 1
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - ANI so far: 1, expected length: 20
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 5 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - Getting ANI digit 5
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - ANI so far: 15, expected length: 20
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 5 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 9 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - Getting ANI digit 9
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - ANI so far: 159, expected length: 20
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 9 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 7 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - Getting ANI digit 7
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - ANI so far: 1597, expected length: 20
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 7 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 4 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - Getting ANI digit 4
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - ANI so far: 15974, expected length: 20
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 4 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 0 [ON]
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - Getting ANI digit 0
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - ANI so far: 159740, expected length: 20
[Sep 30 23:38:51] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 0 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 4 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - Getting ANI digit 4
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - ANI so far: 1597404, expected length: 20
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 4 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 9 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - Getting ANI digit 9
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - ANI so far: 15974049, expected length: 20
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 9 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - Getting ANI digit 1
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - ANI so far: 159740491, expected length: 20
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 4 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - Getting ANI digit 4
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - ANI so far: 1597404914, expected length: 20
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 4 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 4 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - Getting ANI digit 4
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - ANI so far: 15974049144, expected length: 20
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 4 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 5 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << F [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - Got end of ANI
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - Requesting change to Group II with signal 0x33
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 3 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << F [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 3 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [ON]
[Sep 30 23:38:52] VERBOSE[335] chan_dahdi.c: MFC/R2 call offered on chan 5. ANI = 15974049144, DNIS = 1313, Category = National Subscriber
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Accepting MFC/R2 call with charge on chan 5
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 1 [ON]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Rx << 1 [OFF]
[Sep 30 23:38:52] DEBUG[335] chan_dahdi.c: Chan 5 - MF Tx >> 1 [OFF]
[Sep 30 23:38:53] DEBUG[335] chan_dahdi.c: Chan 5 - calling timer 20 (r2_answer_delay) callback
[Sep 30 23:38:53] DEBUG[335] chan_dahdi.c: Enabled echo cancellation on channel 5
[Sep 30 23:38:53] VERBOSE[335] chan_dahdi.c: MFC/R2 call has been accepted on backward channel 5
[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newchannel
Privilege: call,all
Channel: DAHDI/5-1
ChannelState: 4
ChannelStateDesc: Ring
CallerIDNum: 15974049144
CallerIDName: 15974049144
AccountCode:
Exten: 1313
Context: from-pstn
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[335] dsp.c: Setup tone 1100 Hz, 500 ms, block_size=160, hits_required=21
[Sep 30 23:38:53] DEBUG[335] dsp.c: Setup tone 2100 Hz, 2600 ms, block_size=160, hits_required=116
[Sep 30 23:38:53] DEBUG[335] dsp.c: Setup v21 detector, block_size=160
[Sep 30 23:38:53] DEBUG[325] devicestate.c: No provider found, checking channel drivers for DAHDI - 5
[Sep 30 23:38:53] DEBUG[325] devicestate.c: Changing state for DAHDI/5 - state 2 (In use)
[Sep 30 23:38:53] DEBUG[325] devicestate.c: device 'DAHDI/5' state '2'
[Sep 30 23:38:53] DEBUG[366] app_queue.c: Device 'DAHDI/5' changed to state '2' (In use) but we don't care because they're not a member of any queue.
[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: DAHDIChannel
Privilege: call,all
Channel: DAHDI/5-1
Uniqueid: 1412131133.4
DAHDISpan: 1
DAHDIChannel: 5


[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'EXTEN' is '1313'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'NoOp'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [1313@from-pstn:1] NoOp("DAHDI/5-1", "Catch-All DID Match - Found 1313 - You probably want a DID for this.") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Goto'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [1313@from-pstn:2] Goto("DAHDI/5-1", "ext-did,s,1") in new stack
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Goto (ext-did,s,1)
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'EXTEN' is 's'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Set'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@ext-did:1] Set("DAHDI/5-1", "__FROM_DID=s") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Gosub'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@ext-did:2] Gosub("DAHDI/5-1", "app-blacklist-check,s,1") in new stack
[Sep 30 23:38:53] DEBUG[3731] app_stack.c: Channel DAHDI/5-1 has no datastore, so we're allocating one.
[Sep 30 23:38:53] DEBUG[3731] db.c: Unable to find key '15974049144' in family 'blacklist'
[Sep 30 23:38:53] DEBUG[3731] db.c: Unable to find key '15974049144' in family 'blacklist'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Function result is '0'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Expression result is '0'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'GotoIf'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@app-blacklist-check:1] GotoIf("DAHDI/5-1", "0?blacklisted") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Not taking any branch
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Set'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@app-blacklist-check:2] Set("DAHDI/5-1", "CALLED_BLACKLIST=1") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Return'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@app-blacklist-check:3] Return("DAHDI/5-1", "") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Function result is '15974049144'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Expression result is '0'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Function result is '15974049144'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'ExecIf'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@ext-did:3] ExecIf("DAHDI/5-1", "0 ?Set(CALLERID(name)=15974049144)") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Ringing'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@ext-did:4] Ringing("DAHDI/5-1", "") in new stack
[Sep 30 23:38:53] DEBUG[3731] chan_dahdi.c: Requested indication 3 on channel DAHDI/5-1
[Sep 30 23:38:53] DEBUG[325] devicestate.c: No provider found, checking channel drivers for DAHDI - 5
[Sep 30 23:38:53] DEBUG[325] devicestate.c: Changing state for DAHDI/5 - state 6 (Ringing)
[Sep 30 23:38:53] DEBUG[325] devicestate.c: device 'DAHDI/5' state '6'
[Sep 30 23:38:53] DEBUG[366] app_queue.c: Device 'DAHDI/5' changed to state '6' (Ringing) but we don't care because they're not a member of any queue.
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Function result is 'allowed_not_screened'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Set'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@ext-did:5] Set("DAHDI/5-1", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Set'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@ext-did:6] Set("DAHDI/5-1", "CALLERPRES()=allowed_not_screened") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Goto'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@ext-did:7] Goto("DAHDI/5-1", "ext-trunk,3,1") in new stack
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Goto (ext-trunk,3,1)
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Set'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [3@ext-trunk:1] Set("DAHDI/5-1", "TDIAL_STRING=SIP/10019") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Set'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [3@ext-trunk:2] Set("DAHDI/5-1", "DIAL_TRUNK=3") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Goto'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [3@ext-trunk:3] Goto("DAHDI/5-1", "ext-trunk,tdial,1") in new stack
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Goto (ext-trunk,tdial,1)
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'DIAL_TRUNK' is '3'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Set'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [tdial@ext-trunk:1] Set("DAHDI/5-1", "OUTBOUND_GROUP=OUT_3") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'DIAL_TRUNK' is '3'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'OUTMAXCHANS_3' is '1'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Expression result is '0'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'GotoIf'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [tdial@ext-trunk:2] GotoIf("DAHDI/5-1", "0?nomax") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Not taking any branch
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'DIAL_TRUNK' is '3'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Function result is '0'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'DIAL_TRUNK' is '3'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'OUTMAXCHANS_3' is '1'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Expression result is '0'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'GotoIf'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [tdial@ext-trunk:3] GotoIf("DAHDI/5-1", "0?hangit") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Not taking any branch
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'CALLINGPRES_SV' is 'allowed_not_screened'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Expression result is '1'
[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: MFCR2_CATEGORY
Value: National Subscriber
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'CALLINGPRES_SV' is 'allowed_not_screened'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'ExecIf'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [tdial@ext-trunk:4] ExecIf("DAHDI/5-1", "1?Set(CALLERPRES()=allowed_not_screened)") in new stack
[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: from-pstn
Extension: 1313
Priority: 1
Application: NoOp
AppData: Catch-All DID Match - Found 1313 - You probably want a DID for this.
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: from-pstn
Extension: 1313
Priority: 2
Application: Goto
AppData: ext-did,s,1
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-did
Extension: s
Priority: 1
Application: Set
AppData: __FROM_DID=s
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: __FROM_DID
Value: s
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-did
Extension: s
Priority: 2
Application: Gosub
AppData: app-blacklist-check,s,1
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: LOCAL(ARGC)
Value: 0
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: app-blacklist-check
Extension: s
Priority: 1
Application: GotoIf
AppData: 0?blacklisted
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: app-blacklist-check
Extension: s
Priority: 2
Application: Set
AppData: CALLED_BLACKLIST=1
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: CALLED_BLACKLIST
Value: 1
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: app-blacklist-check
Extension: s
Priority: 3
Application: Return
AppData:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: GOSUB_RETVAL
Value:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-did
Extension: s
Priority: 3
Application: ExecIf
AppData: 0 ?Set(CALLERID(name)=15974049144)
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-did
Extension: s
Priority: 4
Application: Ringing
AppData:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newstate
Privilege: call,all
Channel: DAHDI/5-1
ChannelState: 5
ChannelStateDesc: Ringing
CallerIDNum: 15974049144
CallerIDName: 15974049144
ConnectedLineNum:
ConnectedLineName:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-did
Extension: s
Priority: 5
Application: Set
AppData: __CALLINGPRES_SV=allowed_not_screened
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: __CALLINGPRES_SV
Value: allowed_not_screened
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-did
Extension: s
Priority: 6
Application: Set
AppData: CALLERPRES()=allowed_not_screened
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-did
Extension: s
Priority: 7
Application: Goto
AppData: ext-trunk,3,1
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: 3
Priority: 1
Application: Set
AppData: TDIAL_STRING=SIP/10019
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: TDIAL_STRING
Value: SIP/10019
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: 3
Priority: 2
Application: Set
AppData: DIAL_TRUNK=3
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: DIAL_TRUNK
Value: 3
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: 3
Priority: 3
Application: Goto
AppData: ext-trunk,tdial,1
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: tdial
Priority: 1
Application: Set
AppData: OUTBOUND_GROUP=OUT_3
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: OUTBOUND_GROUP
Value: OUT_3
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: tdial
Priority: 2
Application: GotoIf
AppData: 0?nomax
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: tdial
Priority: 3
Application: GotoIf
AppData: 0?hangit
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: tdial
Priority: 4
Application: ExecIf
AppData: 1?Set(CALLERPRES()=allowed_not_screened)
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'FROM_DID' is 's'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Set'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [tdial@ext-trunk:5] Set("DAHDI/5-1", "DIAL_NUMBER=s") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'DIAL_TRUNK' is '3'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'PREFIX_TRUNK_3' is '1'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Expression result is '1'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'DIAL_TRUNK' is '3'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'GosubIf'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [tdial@ext-trunk:6] GosubIf("DAHDI/5-1", "1?sub-flp-3,s,1") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'DIAL_NUMBER' is 's'
[Sep 30 23:38:53] DEBUG[3731] func_strings.c: FUNCTION REGEX (^[0-9#\*\+]+$)(s)
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Function result is '0'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Expression result is '0'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'ExecIf'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@sub-flp-3:1] ExecIf("DAHDI/5-1", "0?Return()") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Return'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [s@sub-flp-3:2] Return("DAHDI/5-1", "") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'DIAL_TRUNK' is '3'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'OUTPREFIX_3' is ''
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'DIAL_NUMBER' is 's'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Set'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [tdial@ext-trunk:7] Set("DAHDI/5-1", "OUTNUM=s") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'TDIAL_STRING' is 'SIP/10019'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'OUTNUM' is 's'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Result of 'DIAL_TRUNK_OPTIONS' is NULL
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Dial'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [tdial@ext-trunk:8] Dial("DAHDI/5-1", "SIP/10019/s,300,") in new stack
[Sep 30 23:38:53] DEBUG[3731] chan_sip.c: Asked to create a SIP channel with formats: 0x8 (alaw)
[Sep 30 23:38:53] DEBUG[3731] chan_sip.c: Allocating new SIP dialog for 5ef816d41c40dd7420b8944b1a7fc360@192.168.2.1:5060 - INVITE (No RTP)
[Sep 30 23:38:53] DEBUG[3731] chan_sip.c: Cant create SIP call - target device not registered
[Sep 30 23:38:53] DEBUG[3731] chan_sip.c: Destroying SIP dialog 5ef816d41c40dd7420b8944b1a7fc360@192.168.2.1:5060
[Sep 30 23:38:53] WARNING[3731] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[Sep 30 23:38:53] VERBOSE[3731] app_dial.c:   == Everyone is busy/congested at this time (1:0/0/1)
[Sep 30 23:38:53] DEBUG[3731] app_dial.c: Exiting with DIALSTATUS=CHANUNAVAIL.
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Function result is '15974049144'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Set'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [tdial@ext-trunk:9] Set("DAHDI/5-1", "CALLERID(number)=15974049144") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Function result is '15974049144'
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Set'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [tdial@ext-trunk:10] Set("DAHDI/5-1", "CALLERID(name)=15974049144") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Launching 'Hangup'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:     -- Executing [tdial@ext-trunk:11] Hangup("DAHDI/5-1", "") in new stack
[Sep 30 23:38:53] DEBUG[3731] pbx.c: Spawn extension (ext-trunk,tdial,11) exited non-zero on 'DAHDI/5-1'
[Sep 30 23:38:53] VERBOSE[3731] pbx.c:   == Spawn extension (ext-trunk, tdial, 11) exited non-zero on 'DAHDI/5-1'
[Sep 30 23:38:53] DEBUG[3731] channel.c: Soft-Hanging up channel 'DAHDI/5-1'
[Sep 30 23:38:53] DEBUG[3731] channel.c: Hanging up channel 'DAHDI/5-1'
[Sep 30 23:38:53] DEBUG[3731] chan_dahdi.c: dahdi_hangup(DAHDI/5-1)
[Sep 30 23:38:53] DEBUG[3731] chan_dahdi.c: Hangup: channel: 5 index = 0, normal = 15, callwait = -1, thirdcall = -1
[Sep 30 23:38:53] DEBUG[3731] chan_dahdi.c: disconnecting MFC/R2 call on chan 5
[Sep 30 23:38:53] DEBUG[3731] chan_dahdi.c: ast cause 20 resulted in openr2 cause 6/Normal Clearing
[Sep 30 23:38:53] DEBUG[3731] chan_dahdi.c: Chan 5 - CAS Tx >> [CLEAR BACK] 0x0C
[Sep 30 23:38:53] DEBUG[3731] chan_dahdi.c: Chan 5 - CAS Raw Tx >> 0x0D
[Sep 30 23:38:53] DEBUG[3731] chan_dahdi.c: Disabled echo cancellation on channel 5
[Sep 30 23:38:53] DEBUG[3731] chan_dahdi.c: Set option TDD MODE, value: OFF(0) on DAHDI/5-1
[Sep 30 23:38:53] DEBUG[3731] chan_dahdi.c: Updated conferencing on 5, with 0 conference users
[Sep 30 23:38:53] VERBOSE[3731] chan_dahdi.c:     -- Hungup 'DAHDI/5-1'
[Sep 30 23:38:53] DEBUG[325] devicestate.c: No provider found, checking channel drivers for DAHDI - 5
[Sep 30 23:38:53] DEBUG[325] devicestate.c: Changing state for DAHDI/5 - state 0 (Unknown)
[Sep 30 23:38:53] DEBUG[325] devicestate.c: device 'DAHDI/5' state '0'
[Sep 30 23:38:53] DEBUG[366] app_queue.c: Device 'DAHDI/5' changed to state '0' (Unknown) but we don't care because they're not a member of any queue.
[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: tdial
Priority: 5
Application: Set
AppData: DIAL_NUMBER=s
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: DIAL_NUMBER
Value: s
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: tdial
Priority: 6
Application: GosubIf
AppData: 1?sub-flp-3,s,1
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: LOCAL(ARGC)
Value: 0
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: sub-flp-3
Extension: s
Priority: 1
Application: ExecIf
AppData: 0?Return()
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: sub-flp-3
Extension: s
Priority: 2
Application: Return
AppData:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: GOSUB_RETVAL
Value:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: tdial
Priority: 7
Application: Set
AppData: OUTNUM=s
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: OUTNUM
Value: s
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: tdial
Priority: 8
Application: Dial
AppData: SIP/10019/s,300,
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: DIALSTATUS
Value:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: DIALEDPEERNUMBER
Value:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: DIALEDPEERNAME
Value:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: ANSWEREDTIME
Value:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: DIALEDTIME
Value:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: VarSet
Privilege: dialplan,all
Channel: DAHDI/5-1
Variable: DIALSTATUS
Value: CHANUNAVAIL
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Dial
Privilege: call,all
SubEvent: End
Channel: DAHDI/5-1
UniqueID: 1412131133.4
DialStatus: CHANUNAVAIL


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: tdial
Priority: 9
Application: Set
AppData: CALLERID(number)=15974049144
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: tdial
Priority: 10
Application: Set
AppData: CALLERID(name)=15974049144
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Newexten
Privilege: dialplan,all
Channel: DAHDI/5-1
Context: ext-trunk
Extension: tdial
Priority: 11
Application: Hangup
AppData:
Uniqueid: 1412131133.4


[Sep 30 23:38:53] DEBUG[373] manager.c: Examining event:
Event: Hangup
Privilege: call,all
Channel: DAHDI/5-1
Uniqueid: 1412131133.4
CallerIDNum: 15974049144
CallerIDName: 15974049144
ConnectedLineNum: <unknown>
ConnectedLineName: <unknown>
Cause: 20
Cause-txt: Subscriber absent


[Sep 30 23:38:53] DEBUG[350] chan_sip.c: = Looking for  Call ID: 1347076923-40715-1@BJC.BGI.C.IG (Checking From) --From tag 774857963 --To-tag
[Sep 30 23:38:53] DEBUG[350] netsock2.c: Splitting '192.168.2.84' into...
[Sep 30 23:38:53] DEBUG[350] netsock2.c: ...host '192.168.2.84' and port ''.
[Sep 30 23:38:53] DEBUG[350] netsock2.c: Splitting '192.168.2.84' into...
[Sep 30 23:38:53] DEBUG[350] netsock2.c: ...host '192.168.2.84' and port ''.
[Sep 30 23:38:53] DEBUG[350] chan_sip.c: **** Received REGISTER (2) - Command in SIP REGISTER
[Sep 30 23:38:53] DEBUG[350] netsock2.c: Splitting '192.168.2.86:40715' into...
[Sep 30 23:38:53] DEBUG[350] netsock2.c: ...host '192.168.2.86' and port '40715'.
[Sep 30 23:38:53] DEBUG[350] netsock2.c: Splitting '192.168.2.84' into...
[Sep 30 23:38:53] DEBUG[350] netsock2.c: ...host '192.168.2.84' and port ''.
[Sep 30 23:38:53] DEBUG[350] chan_sip.c: Trying to put 'SIP/2.0 403' onto UDP socket destined for 192.168.2.86:40715
[Sep 30 23:38:53] NOTICE[350] chan_sip.c: Registration from '<sip:23@192.168.2.84>' failed for '192.168.2.86:40715' - No matching peer found
[Sep 30 23:38:57] DEBUG[335] chan_dahdi.c: Chan 5 - Bits changed from 0x00 to 0x08
[Sep 30 23:38:57] DEBUG[335] chan_dahdi.c: Chan 5 - CAS Rx << [CLEAR FORWARD] 0x08
[Sep 30 23:38:57] DEBUG[335] chan_dahdi.c: Chan 5 - Call ended
[Sep 30 23:38:57] DEBUG[335] chan_dahdi.c: Chan 5 - CAS Tx >> [IDLE] 0x08
[Sep 30 23:38:57] DEBUG[335] chan_dahdi.c: Chan 5 - CAS Raw Tx >> 0x09
[Sep 30 23:38:57] VERBOSE[335] chan_dahdi.c: MFC/R2 call end on channel 5

Topic		Replies	Views
Configure Asterisk to be a sip client by 3CX Sipserver Asterisk Support	0	353	July 3, 2008
Hi I'm trying to Integrate Asterisk to 3Cx Asterisk SIP	5	468	June 9, 2023
Configuration Problen in sip.conf or extension.conf HELP Asterisk Support	2	321	July 25, 2012
Unable to register any SIP clients Asterisk Support	1	287	November 4, 2011
Setup Asterisk (Freepbx) as voip provider for 3cx Asterisk Support	0	217	February 18, 2011

Asterisk to 3CX bridging

Related topics