[Asterisk-Users] Unable to accept incoming PSTN calls

Wed Apr 26 20:56:26 MST 2006

I am new to Asterisk and the protocol/language complex world of VoIp and PBX. But I have a dedicated machine running A at H 2.8, a single TDM400P with one FXS module card connected to a standard analog phone. The second card is an X100P connected to my analog PSTN phone line. I also have Grandsteam IP phone plugged into the network and a couple of x-lite SIP softphones. I can make outgoing calls on the Grandstream or any registered SIP sofware phone from any computer. I can also get a dial tone from the analog phone connected to the ZAP X100P port. But when incoming callas come in, none of the phones ring. No VoIP trunks, just the single ZAP trunk from the X100P. Below are my configurations and a tail of /var/log/asterisk/full when making a call from an outside line. There is much more in the extensions.conf file but I was not sure how much to include and noticed in another post that only a couple sections were included. Also, when making an outside PSTN call comes in the other non-asterisk-connected phones in the house ring fine, but none of the asterisk-connected extensions/phones?

sip.conf file:

[general]

bindport=5060 ; UDP Port to bind to (SIP standard port is 5060)
bindaddr=0.0.0.0 ; IP address to bind to (0.0.0.0 binds to all)
disallow=all
allow=ulaw
allow=alaw
context = from-sip-external ; Send unknown SIP callers to this context
callerid = Unknown

#include sip_nat.conf
#include sip_custom.conf
#include sip_additional.conf
#include additional_a2billing_sip.conf
extensions.conf:

zapata.conf file:

;
; Zapata telephony interface
;
; Configuration file

[trunkgroups]

[channels]

language=en
context=from-pstn
signalling=fxs_ks
rxwink=300		; Atlas seems to use long (250ms) winks
;
; Whether or not to do distinctive ring detection on FXO lines
;
;usedistinctiveringdetection=yes

usecallerid=yes
hidecallerid=no
callwaiting=yes
usecallingpres=yes
callwaitingcallerid=yes
threewaycalling=yes
transfer=yes
cancallforward=yes
callreturn=yes
echocancel=yes
echocancelwhenbridged=no
echotraining=800
rxgain=0.0
txgain=0.0
group=0
callgroup=1
pickupgroup=1
immediate=no

;faxdetect=both
faxdetect=incoming
;faxdetect=outgoing
;faxdetect=no

;Include genzaptelconf configs
#include zapata-auto.conf

group=1

;Include AMP configs
#include zapata_additional.conf

extensions.conf file:

; include extension contexts generated from AMP
#include extensions_additional.conf

; Customizations to this dialplan should be made in extensions_custom.conf
; See extensions_custom.conf.sample for an example
#include extensions_custom.conf

[from-trunk] ; just an alias since VoIP shouldn't be called PSTN
include => from-pstn

[from-pstn]
include => from-pstn-custom ; create this context in extensions_custom.conf to include customizations
include => ext-did
;exten => fax,1,Goto(ext-fax,in_fax,1)
exten => _.,1,Wait(1)
exten => _.,2,Goto(from-pstn,s,1)

var/log/asterisk/full (when recieving a call from pstn):

Apr 26 18:43:33 VERBOSE[2696] logger.c: -- Remote UNIX connection
Apr 26 18:43:52 VERBOSE[25804] logger.c: -- Remote UNIX connection disconnected
Apr 26 18:44:57 VERBOSE[25810] logger.c: -- Starting simple switch on 'Zap/1-1'
Apr 26 18:44:59 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:00 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:00 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:00 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:00 DEBUG[2775] manager.c: Manager received command 'Command'
Apr 26 18:45:00 DEBUG[2775] manager.c: Manager received command 'Command'
Apr 26 18:45:01 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:01 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:01 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:01 DEBUG[25810] chan_zap.c: Exception on 17, channel 1
Apr 26 18:45:01 DEBUG[25810] chan_zap.c: Got event Ring Begin(1Cool on channel 1 (index 0)
Apr 26 18:45:02 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:02 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:02 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:03 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:03 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:03 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:03 DEBUG[25810] chan_zap.c: Exception on 17, channel 1
Apr 26 18:45:03 DEBUG[25810] chan_zap.c: Got event Ring/Answered(2) on channel 1 (index 0)
Apr 26 18:45:03 DEBUG[25810] chan_zap.c: Setting IDLE polarity due to ring. Old polarity was 0
Apr 26 18:45:04 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:04 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:04 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:05 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:05 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:05 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:06 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:06 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:06 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:07 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:07 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:07 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:07 DEBUG[25810] chan_zap.c: Exception on 17, channel 1
Apr 26 18:45:07 DEBUG[25810] chan_zap.c: Got event Ring Begin(1Cool on channel 1 (index 0)
Apr 26 18:45:08 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:08 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:08 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:09 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:09 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:09 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:09 DEBUG[25810] chan_zap.c: Exception on 17, channel 1
Apr 26 18:45:09 DEBUG[25810] chan_zap.c: Got event Ring/Answered(2) on channel 1 (index 0)
Apr 26 18:45:09 DEBUG[25810] chan_zap.c: Setting IDLE polarity due to ring. Old polarity was 0
Apr 26 18:45:10 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:10 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:10 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:11 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:11 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:11 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:12 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:12 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:12 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:13 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:13 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:13 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:13 DEBUG[25810] chan_zap.c: Exception on 17, channel 1
Apr 26 18:45:13 DEBUG[25810] chan_zap.c: Got event Ring Begin(1Cool on channel 1 (index 0)
Apr 26 18:45:14 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:14 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:14 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:15 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:15 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:15 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:16 DEBUG[25810] chan_zap.c: Exception on 17, channel 1
Apr 26 18:45:16 DEBUG[25810] chan_zap.c: Got event Ring/Answered(2) on channel 1 (index 0)
Apr 26 18:45:16 DEBUG[25810] chan_zap.c: Setting IDLE polarity due to ring. Old polarity was 0
Apr 26 18:45:16 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:16 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:16 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:17 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:17 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:17 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:18 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:18 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:18 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:19 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:19 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:19 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:20 DEBUG[25810] chan_zap.c: Exception on 17, channel 1
Apr 26 18:45:20 DEBUG[25810] chan_zap.c: Got event Ring Begin(1Cool on channel 1 (index 0)
Apr 26 18:45:20 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:20 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:20 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:21 DEBUG[25810] chan_zap.c: Exception on 17, channel 1
Apr 26 18:45:21 DEBUG[25810] chan_zap.c: Got event Ring/Answered(2) on channel 1 (index 0)
Apr 26 18:45:21 DEBUG[25810] chan_zap.c: Setting IDLE polarity due to ring. Old polarity was 0
Apr 26 18:45:22 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:22 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:22 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:23 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:23 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:23 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:24 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:24 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:24 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:25 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:25 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:25 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:26 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:26 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:26 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:27 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:27 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:27 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:28 VERBOSE[25810] logger.c: -- Executing Goto("Zap/1-1", "from-pstn|s|1") in new stack
Apr 26 18:45:28 VERBOSE[25810] logger.c: -- Goto (from-pstn,s,1)
Apr 26 18:45:28 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:29 VERBOSE[25810] logger.c: == Spawn extension (from-pstn, s, 1) exited non-zero on 'Zap/1-1'
Apr 26 18:45:29 VERBOSE[25810] logger.c: -- Executing Wait("Zap/1-1", "1") in new stack
Apr 26 18:45:29 VERBOSE[25810] logger.c: == Spawn extension (from-pstn, h, 1) exited non-zero on 'Zap/1-1'
Apr 26 18:45:29 DEBUG[25810] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Apr 26 18:45:29 DEBUG[25810] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES ('2006-04-26 18:44:59','6048481125','6048481125','s','from-pstn', 'Zap/1-1','','Wait','1',30,0,'NO ANSWER',3,'','1146102297.1')
Apr 26 18:45:29 DEBUG[25810] chan_zap.c: Hangup: channel: 1 index = 0, normal = 17, callwait = -1, thirdcall = -1
Apr 26 18:45:29 DEBUG[25810] chan_zap.c: disabled echo cancellation on channel 1
Apr 26 18:45:29 DEBUG[25810] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/1-1
Apr 26 18:45:29 DEBUG[25810] chan_zap.c: Updated conferencing on 1, with 0 conference users
Apr 26 18:45:29 VERBOSE[25810] logger.c: -- Hungup 'Zap/1-1'