Calls get cut off after 3-5 minutes


#1

I’m using *@home 2.8 with a 4port analog card. I have several issues with *, but this one is really annoying- today all the calls I made have been cut-off after a few minutes of calling. I suddenly stop hearing the other person, and after a few seconds hearing scratching and an echo of myself… Here’s my configs and the log- hopefully someone can see something there… Thanks in advance!

zapata.conf:
[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=-3.0
txgain=8.0
callprogress=yes
busydetect=yes
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

zaptel.conf:
fxsks=1
fxsks=2
fxsks=3
fxsks=4
loadzone=nz
defaultzone=nz

(nz exists in indications.conf with values from voip-wiki, modprobe.conf was added the options for wctdm/wcfxs opermode=NEWZEALAND)
Logfile:
May 9 00:35:29 DEBUG[2855] chan_sip.c: Setting NAT on RTP to 0
May 9 00:35:29 DEBUG[2855] chan_sip.c: Stopping retransmission on ‘8057be1c-c0a81169-13c4-4460c4ce-6cdf162-5b04@192.168.21.5’ of Response 1: Match Found
May 9 00:35:29 DEBUG[2855] chan_sip.c: Setting NAT on RTP to 0
May 9 00:35:29 DEBUG[2855] chan_sip.c: Checking SIP call limits for device 201
May 9 00:35:29 DEBUG[2855] chan_sip.c: build_route: Contact hop:
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “dialout-trunk|4|3606394||”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?3:2”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Goto (macro-dialout-trunk,s,3)
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “user-callerid”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “AMPUSER=201”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:35:29 DEBUG[6786] db.c: Unable to find key ‘201/emergency_cid’ in family 'DEVICE’
May 9 00:35:29 DEBUG[6786] func_db.c: DB: DEVICE/201/emergency_cid not found in database.
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is ''
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “EMERGENCYCID=”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is 'Chris’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “AMPUSERCIDNAME=Chris”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?6”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “CALLERID(all)=“Chris” <201>”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '“Chris” <201>'
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “Using CallerID “Chris” <201>”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “record-enable|201|OUT”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '0’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0 > 0?2:4”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Goto (macro-record-enable,s,4)
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing AGI(“SIP/201-ef3c”, “recordingcheck|20060509-003529|1147149329.66”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 9 00:35:29 VERBOSE[6786] logger.c: recordingcheck|20060509-003529|1147149329.66: Outbound recording not enabled
May 9 00:35:29 VERBOSE[6786] logger.c: – AGI Script recordingcheck completed, returning 0
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “No recording needed”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “outbound-callerid|4”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is ''
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “USEROUTCID=”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?4”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,4)
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?6”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,6)
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?8”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,8)
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '“Chris” <201>'
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “CallerID set to “Chris” <201>”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “GROUP()=OUT_4”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '1’
May 9 00:35:29 WARNING[6786] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected $end, expecting TOK_MINUS or TOK_COMPL or TOK_LP or TOKEN; Input:
1 >
^
May 9 00:35:29 WARNING[6786] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source.
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?108”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “DIAL_NUMBER=3606394”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “DIAL_TRUNK=4”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing AGI(“SIP/201-ef3c”, “fixlocalprefix”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
May 9 00:35:29 VERBOSE[6786] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
May 9 00:35:29 VERBOSE[6786] logger.c: – AGI Script fixlocalprefix completed, returning 0
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “OUTNUM=3606394”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is 'ZAP/3’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “custom=ZAP/3”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?16”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Dial(“SIP/201-ef3c”, “ZAP/3/3606394|120|W”) in new stack
May 9 00:35:29 DEBUG[6786] dsp.c: dsp busy pattern set to 0,0
May 9 00:35:29 DEBUG[6786] chan_zap.c: Dialing '3606394’
May 9 00:35:29 DEBUG[6786] chan_zap.c: Deferring dialing…
May 9 00:35:29 VERBOSE[6786] logger.c: – Called 3/3606394
May 9 00:35:30 DEBUG[6786] chan_zap.c: Exception on 20, channel 3
May 9 00:35:30 DEBUG[6786] chan_zap.c: Got event Hook Transition Complete(12) on channel 3 (index 0)
May 9 00:35:31 DEBUG[6786] chan_zap.c: Exception on 20, channel 3
May 9 00:35:31 DEBUG[6786] chan_zap.c: Got event Dial Complete(9) on channel 3 (index 0)
May 9 00:35:31 DEBUG[6786] chan_zap.c: Enabled echo cancellation on channel 3
May 9 00:35:31 DEBUG[6786] chan_zap.c: Engaged echo training on channel 3
May 9 00:35:33 DEBUG[2855] chan_sip.c: Auto destroying call '23ad8e504dfd7dbd133424f300a36f14@192.168.21.5’
May 9 00:35:33 DEBUG[6786] chan_zap.c: Exception on 20, channel 3
May 9 00:35:33 DEBUG[6786] chan_zap.c: Got event Dial Complete(9) on channel 3 (index 0)
May 9 00:35:33 DEBUG[6786] chan_zap.c: Echo cancellation already on
May 9 00:35:33 DEBUG[6786] chan_zap.c: Done dialing, but waiting for progress detection before doing more…
May 9 00:36:03 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:36:06 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:36:06 DEBUG[2855] chan_sip.c: Auto destroying call '77047f080d3aa27a75e3997502985a07@192.168.21.5’
May 9 00:36:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:36:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:36:46 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:36:50 DEBUG[2855] chan_sip.c: Auto destroying call '3c7ecbe9496df2ab5ad86d283ace1e26@192.168.21.5’
May 9 00:36:54 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:37:27 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:37:34 DEBUG[2855] chan_sip.c: Auto destroying call '6e49f70a1df883d2110d222410398dde@192.168.21.5’
May 9 00:37:36 VERBOSE[6786] logger.c: – Nobody picked up in 120000 ms
May 9 00:37:36 DEBUG[6786] chan_zap.c: Hangup: channel: 3 index = 0, normal = 20, callwait = -1, thirdcall = -1
May 9 00:37:36 DEBUG[6786] chan_zap.c: disabled echo cancellation on channel 3
May 9 00:37:36 DEBUG[6786] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/3-1
May 9 00:37:36 DEBUG[6786] chan_zap.c: Updated conferencing on 3, with 0 conference users
May 9 00:37:36 VERBOSE[6786] logger.c: – Hungup 'Zap/3-1’
May 9 00:37:36 DEBUG[6786] app_dial.c: Exiting with DIALSTATUS=NOANSWER.
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Goto(“SIP/201-ef3c”, “s-NOANSWER|1”) in new stack
May 9 00:37:36 VERBOSE[6786] logger.c: – Goto (macro-dialout-trunk,s-NOANSWER,1)
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “Dial failed due to NOANSWER”) in new stack
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “dialout-trunk|3|3606394||”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?3:2”) in new stack
May 9 00:37:36 VERBOSE[6786] logger.c: – Goto (macro-dialout-trunk,s,3)
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “user-callerid”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “AMPUSER=201”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:37:36 DEBUG[6786] db.c: Unable to find key ‘201/emergency_cid’ in family 'DEVICE’
May 9 00:37:36 DEBUG[6786] func_db.c: DB: DEVICE/201/emergency_cid not found in database.
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is ''
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “EMERGENCYCID=”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is 'Chris’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “AMPUSERCIDNAME=Chris”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?6”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “CALLERID(all)=“Chris” <201>”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '“Chris” <201>'
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “Using CallerID “Chris” <201>”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “record-enable|201|OUT”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '0’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0 > 0?2:4”) in new stack
May 9 00:37:36 VERBOSE[6786] logger.c: – Goto (macro-record-enable,s,4)
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing AGI(“SIP/201-ef3c”, “recordingcheck|20060509-003736|1147149329.66”) in new stack
May 9 00:37:36 VERBOSE[6786] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 9 00:37:37 VERBOSE[6786] logger.c: recordingcheck|20060509-003736|1147149329.66: Outbound recording not enabled
May 9 00:37:37 VERBOSE[6786] logger.c: – AGI Script recordingcheck completed, returning 0
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “No recording needed”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “outbound-callerid|3”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:37:37 DEBUG[6786] pbx.c: Function result is ''
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “USEROUTCID=”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?4”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,4)
May 9 00:37:37 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?6”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,6)
May 9 00:37:37 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?8”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,8)
May 9 00:37:37 DEBUG[6786] pbx.c: Function result is '“Chris” <201>'
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “CallerID set to “Chris” <201>”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “GROUP()=OUT_3”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Function result is '1’
May 9 00:37:37 WARNING[6786] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected $end, expecting TOK_MINUS or TOK_COMPL or TOK_LP or TOKEN; Input:
1 >
^
May 9 00:37:37 WARNING[6786] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source.
May 9 00:37:37 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?108”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “DIAL_NUMBER=3606394”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “DIAL_TRUNK=3”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing AGI(“SIP/201-ef3c”, “fixlocalprefix”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
May 9 00:37:37 VERBOSE[6786] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
May 9 00:37:37 VERBOSE[6786] logger.c: – AGI Script fixlocalprefix completed, returning 0
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “OUTNUM=3606394”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Function result is 'ZAP/2’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “custom=ZAP/2”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?16”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Dial(“SIP/201-ef3c”, “ZAP/2/3606394|120|W”) in new stack
May 9 00:37:37 DEBUG[6786] dsp.c: dsp busy pattern set to 0,0
May 9 00:37:37 DEBUG[6786] chan_zap.c: Dialing '3606394’
May 9 00:37:37 DEBUG[6786] chan_zap.c: Deferring dialing…
May 9 00:37:37 VERBOSE[6786] logger.c: – Called 2/3606394
May 9 00:37:37 DEBUG[6786] chan_zap.c: Exception on 18, channel 2
May 9 00:37:37 DEBUG[6786] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
May 9 00:37:39 DEBUG[6786] chan_zap.c: Exception on 18, channel 2
May 9 00:37:39 DEBUG[6786] chan_zap.c: Got event Dial Complete(9) on channel 2 (index 0)
May 9 00:37:39 DEBUG[6786] chan_zap.c: Enabled echo cancellation on channel 2
May 9 00:37:39 DEBUG[6786] chan_zap.c: Engaged echo training on channel 2
May 9 00:37:41 DEBUG[6786] chan_zap.c: Exception on 18, channel 2
May 9 00:37:41 DEBUG[6786] chan_zap.c: Got event Dial Complete(9) on channel 2 (index 0)
May 9 00:37:41 DEBUG[6786] chan_zap.c: Echo cancellation already on
May 9 00:37:41 DEBUG[6786] chan_zap.c: Done dialing, but waiting for progress detection before doing more…
May 9 00:37:44 DEBUG[2855] chan_sip.c: Auto destroying call ‘c0a81175-13c4-14-531b-420f’
May 9 00:37:47 DEBUG[6786] chan_zap.c: Hangup: channel: 2 index = 0, normal = 18, callwait = -1, thirdcall = -1
May 9 00:37:47 DEBUG[6786] chan_zap.c: disabled echo cancellation on channel 2
May 9 00:37:47 DEBUG[6786] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/2-1
May 9 00:37:47 DEBUG[6786] chan_zap.c: Updated conferencing on 2, with 0 conference users
May 9 00:37:47 VERBOSE[6786] logger.c: – Hungup ‘Zap/2-1’
May 9 00:37:47 DEBUG[6786] app_dial.c: Exiting with DIALSTATUS=CANCEL.
May 9 00:37:47 VERBOSE[6786] logger.c: == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on ‘SIP/201-ef3c’ in macro ‘dialout-trunk’
May 9 00:37:47 VERBOSE[6786] logger.c: == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on ‘SIP/201-ef3c’
May 9 00:37:47 DEBUG[6786] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
May 9 00:37:47 DEBUG[6786] 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-05-09 00:35:29’,’“Chris” <201>’,‘201’,‘3606394’,‘from-internal’, ‘SIP/201-ef3c’,‘Zap/2-1’,‘Dial’,‘ZAP/2/3606394|120|W’,138,0,‘NO ANSWER’,3,’’,‘1147149329.66’)
May 9 00:37:47 DEBUG[6786] chan_sip.c: update_call_counter(201) - decrement call limit counter
May 9 00:37:47 DEBUG[2855] chan_sip.c: Stopping retransmission on ‘8057be1c-c0a81169-13c4-4460c4ce-6cdf162-5b04@192.168.21.5’ of Response 2: Match Found
May 9 00:38:08 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:38:18 DEBUG[2855] chan_sip.c: Auto destroying call '42063bc21386e7113703a07f35a3491f@192.168.21.5’
May 9 00:38:34 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:38:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:38:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:38:48 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:38:51 DEBUG[2855] chan_sip.c: Auto destroying call '46fe11d44ae1799d41e5f8a54f209b20@192.168.21.5’
May 9 00:39:25 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:39:29 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:39:35 DEBUG[2855] chan_sip.c: Auto destroying call '134749b76e1fb6df28edd50515190a60@192.168.21.5’
May 9 00:40:09 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:40:15 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:40:19 DEBUG[2855] chan_sip.c: Auto destroying call '46617b2d576e9d5167a82b2f6d005193@192.168.21.5’
May 9 00:40:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:40:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:40:50 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:40:52 DEBUG[2855] chan_sip.c: Auto destroying call '1d5f544537cdceaa79d40a6d0d5af17e@192.168.21.5’
May 9 00:41:05 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:41:31 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:41:36 DEBUG[2855] chan_sip.c: Auto destroying call '68d4ffa716e3e7915de1f96528c37303@192.168.21.5’
May 9 00:41:55 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:42:11 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:42:20 DEBUG[2855] chan_sip.c: Auto destroying call '56d9f28520b3499133fa9348221a4a27@192.168.21.5’
May 9 00:42:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:42:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:42:46 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:42:52 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:42:53 DEBUG[2855] chan_sip.c: Auto destroying call '4f2d32f877962a533df7a581156642d2@192.168.21.5’
May 9 00:43:32 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:43:36 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:43:37 DEBUG[2855] chan_sip.c: Auto destroying call '0751ddc00125e82d0e80d9ab52df6ba8@192.168.21.5’
May 9 00:44:13 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:44:21 DEBUG[2855] chan_sip.c: Auto destroying call '73951f5a442149122cf648b943da304b@192.168.21.5’
May 9 00:44:26 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:44:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:44:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:44:54 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:44:54 DEBUG[2855] chan_sip.c: Auto destroying call '07cfd2d2514f3a3528292cbb48dc764c@192.168.21.5’
May 9 00:45:17 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:45:35 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:45:38 DEBUG[2855] chan_sip.c: Auto destroying call '0055dd0d15640fb5649142ad5c276d01@192.168.21.5’
May 9 00:45:38 DEBUG[2855] chan_sip.c: Auto destroying call '0055dd0d15640fb5649142ad5c276d


#2

[quote=“Chris911”]I’m using *@home 2.8 with a 4port analog card. I have several issues with *, but this one is really annoying- today all the calls I made have been cut-off after a few minutes of calling. I suddenly stop hearing the other person, and after a few seconds hearing scratching and an echo of myself… Here’s my configs and the log- hopefully someone can see something there… Thanks in advance!

zapata.conf:
[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=-3.0
txgain=8.0
callprogress=yes
busydetect=yes
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

zaptel.conf:
fxsks=1
fxsks=2
fxsks=3
fxsks=4
loadzone=nz
defaultzone=nz

(nz exists in indications.conf with values from voip-wiki, modprobe.conf was added the options for wctdm/wcfxs opermode=NEWZEALAND)
Logfile:
May 9 00:35:29 DEBUG[2855] chan_sip.c: Setting NAT on RTP to 0
May 9 00:35:29 DEBUG[2855] chan_sip.c: Stopping retransmission on ‘8057be1c-c0a81169-13c4-4460c4ce-6cdf162-5b04@192.168.21.5’ of Response 1: Match Found
May 9 00:35:29 DEBUG[2855] chan_sip.c: Setting NAT on RTP to 0
May 9 00:35:29 DEBUG[2855] chan_sip.c: Checking SIP call limits for device 201
May 9 00:35:29 DEBUG[2855] chan_sip.c: build_route: Contact hop:
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “dialout-trunk|4|3606394||”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?3:2”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Goto (macro-dialout-trunk,s,3)
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “user-callerid”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “AMPUSER=201”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:35:29 DEBUG[6786] db.c: Unable to find key ‘201/emergency_cid’ in family 'DEVICE’
May 9 00:35:29 DEBUG[6786] func_db.c: DB: DEVICE/201/emergency_cid not found in database.
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is ''
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “EMERGENCYCID=”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is 'Chris’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “AMPUSERCIDNAME=Chris”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?6”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “CALLERID(all)=“Chris” <201>”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '“Chris” <201>'
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “Using CallerID “Chris” <201>”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “record-enable|201|OUT”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '0’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0 > 0?2:4”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Goto (macro-record-enable,s,4)
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing AGI(“SIP/201-ef3c”, “recordingcheck|20060509-003529|1147149329.66”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 9 00:35:29 VERBOSE[6786] logger.c: recordingcheck|20060509-003529|1147149329.66: Outbound recording not enabled
May 9 00:35:29 VERBOSE[6786] logger.c: – AGI Script recordingcheck completed, returning 0
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “No recording needed”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “outbound-callerid|4”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is ''
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “USEROUTCID=”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?4”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,4)
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?6”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,6)
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?8”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,8)
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '“Chris” <201>'
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “CallerID set to “Chris” <201>”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “GROUP()=OUT_4”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is '1’
May 9 00:35:29 WARNING[6786] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected $end, expecting TOK_MINUS or TOK_COMPL or TOK_LP or TOKEN; Input:
1 >
^
May 9 00:35:29 WARNING[6786] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source.
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?108”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “DIAL_NUMBER=3606394”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “DIAL_TRUNK=4”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing AGI(“SIP/201-ef3c”, “fixlocalprefix”) in new stack
May 9 00:35:29 VERBOSE[6786] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
May 9 00:35:29 VERBOSE[6786] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
May 9 00:35:29 VERBOSE[6786] logger.c: – AGI Script fixlocalprefix completed, returning 0
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “OUTNUM=3606394”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Function result is 'ZAP/3’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “custom=ZAP/3”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?16”) in new stack
May 9 00:35:29 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:35:29 VERBOSE[6786] logger.c: – Executing Dial(“SIP/201-ef3c”, “ZAP/3/3606394|120|W”) in new stack
May 9 00:35:29 DEBUG[6786] dsp.c: dsp busy pattern set to 0,0
May 9 00:35:29 DEBUG[6786] chan_zap.c: Dialing '3606394’
May 9 00:35:29 DEBUG[6786] chan_zap.c: Deferring dialing…
May 9 00:35:29 VERBOSE[6786] logger.c: – Called 3/3606394
May 9 00:35:30 DEBUG[6786] chan_zap.c: Exception on 20, channel 3
May 9 00:35:30 DEBUG[6786] chan_zap.c: Got event Hook Transition Complete(12) on channel 3 (index 0)
May 9 00:35:31 DEBUG[6786] chan_zap.c: Exception on 20, channel 3
May 9 00:35:31 DEBUG[6786] chan_zap.c: Got event Dial Complete(9) on channel 3 (index 0)
May 9 00:35:31 DEBUG[6786] chan_zap.c: Enabled echo cancellation on channel 3
May 9 00:35:31 DEBUG[6786] chan_zap.c: Engaged echo training on channel 3
May 9 00:35:33 DEBUG[2855] chan_sip.c: Auto destroying call '23ad8e504dfd7dbd133424f300a36f14@192.168.21.5’
May 9 00:35:33 DEBUG[6786] chan_zap.c: Exception on 20, channel 3
May 9 00:35:33 DEBUG[6786] chan_zap.c: Got event Dial Complete(9) on channel 3 (index 0)
May 9 00:35:33 DEBUG[6786] chan_zap.c: Echo cancellation already on
May 9 00:35:33 DEBUG[6786] chan_zap.c: Done dialing, but waiting for progress detection before doing more…
May 9 00:36:03 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:36:06 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:36:06 DEBUG[2855] chan_sip.c: Auto destroying call '77047f080d3aa27a75e3997502985a07@192.168.21.5’
May 9 00:36:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:36:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:36:46 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:36:50 DEBUG[2855] chan_sip.c: Auto destroying call '3c7ecbe9496df2ab5ad86d283ace1e26@192.168.21.5’
May 9 00:36:54 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:37:27 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:37:34 DEBUG[2855] chan_sip.c: Auto destroying call '6e49f70a1df883d2110d222410398dde@192.168.21.5’
May 9 00:37:36 VERBOSE[6786] logger.c: – Nobody picked up in 120000 ms
May 9 00:37:36 DEBUG[6786] chan_zap.c: Hangup: channel: 3 index = 0, normal = 20, callwait = -1, thirdcall = -1
May 9 00:37:36 DEBUG[6786] chan_zap.c: disabled echo cancellation on channel 3
May 9 00:37:36 DEBUG[6786] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/3-1
May 9 00:37:36 DEBUG[6786] chan_zap.c: Updated conferencing on 3, with 0 conference users
May 9 00:37:36 VERBOSE[6786] logger.c: – Hungup 'Zap/3-1’
May 9 00:37:36 DEBUG[6786] app_dial.c: Exiting with DIALSTATUS=NOANSWER.
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Goto(“SIP/201-ef3c”, “s-NOANSWER|1”) in new stack
May 9 00:37:36 VERBOSE[6786] logger.c: – Goto (macro-dialout-trunk,s-NOANSWER,1)
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “Dial failed due to NOANSWER”) in new stack
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “dialout-trunk|3|3606394||”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?3:2”) in new stack
May 9 00:37:36 VERBOSE[6786] logger.c: – Goto (macro-dialout-trunk,s,3)
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “user-callerid”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “AMPUSER=201”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:37:36 DEBUG[6786] db.c: Unable to find key ‘201/emergency_cid’ in family 'DEVICE’
May 9 00:37:36 DEBUG[6786] func_db.c: DB: DEVICE/201/emergency_cid not found in database.
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is ''
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “EMERGENCYCID=”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is 'Chris’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “AMPUSERCIDNAME=Chris”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?6”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “CALLERID(all)=“Chris” <201>”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '“Chris” <201>'
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “Using CallerID “Chris” <201>”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “record-enable|201|OUT”) in new stack
May 9 00:37:36 DEBUG[6786] pbx.c: Function result is '0’
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0 > 0?2:4”) in new stack
May 9 00:37:36 VERBOSE[6786] logger.c: – Goto (macro-record-enable,s,4)
May 9 00:37:36 VERBOSE[6786] logger.c: – Executing AGI(“SIP/201-ef3c”, “recordingcheck|20060509-003736|1147149329.66”) in new stack
May 9 00:37:36 VERBOSE[6786] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
May 9 00:37:37 VERBOSE[6786] logger.c: recordingcheck|20060509-003736|1147149329.66: Outbound recording not enabled
May 9 00:37:37 VERBOSE[6786] logger.c: – AGI Script recordingcheck completed, returning 0
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “No recording needed”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Macro(“SIP/201-ef3c”, “outbound-callerid|3”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Function result is '201’
May 9 00:37:37 DEBUG[6786] pbx.c: Function result is ''
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “USEROUTCID=”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?4”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,4)
May 9 00:37:37 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?6”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,6)
May 9 00:37:37 DEBUG[6786] pbx.c: Expression result is '1’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “1?8”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Goto (macro-outbound-callerid,s,8)
May 9 00:37:37 DEBUG[6786] pbx.c: Function result is '“Chris” <201>'
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing NoOp(“SIP/201-ef3c”, “CallerID set to “Chris” <201>”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “GROUP()=OUT_3”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Function result is '1’
May 9 00:37:37 WARNING[6786] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected $end, expecting TOK_MINUS or TOK_COMPL or TOK_LP or TOKEN; Input:
1 >
^
May 9 00:37:37 WARNING[6786] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source.
May 9 00:37:37 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?108”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “DIAL_NUMBER=3606394”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “DIAL_TRUNK=3”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing AGI(“SIP/201-ef3c”, “fixlocalprefix”) in new stack
May 9 00:37:37 VERBOSE[6786] logger.c: – Launched AGI Script /var/lib/asterisk/agi-bin/fixlocalprefix
May 9 00:37:37 VERBOSE[6786] logger.c: fixlocalprefix: Could not parse /etc/asterisk/localprefixes.conf
May 9 00:37:37 VERBOSE[6786] logger.c: – AGI Script fixlocalprefix completed, returning 0
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “OUTNUM=3606394”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Function result is 'ZAP/2’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Set(“SIP/201-ef3c”, “custom=ZAP/2”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Expression result is '0’
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing GotoIf(“SIP/201-ef3c”, “0?16”) in new stack
May 9 00:37:37 DEBUG[6786] pbx.c: Not taking any branch
May 9 00:37:37 VERBOSE[6786] logger.c: – Executing Dial(“SIP/201-ef3c”, “ZAP/2/3606394|120|W”) in new stack
May 9 00:37:37 DEBUG[6786] dsp.c: dsp busy pattern set to 0,0
May 9 00:37:37 DEBUG[6786] chan_zap.c: Dialing '3606394’
May 9 00:37:37 DEBUG[6786] chan_zap.c: Deferring dialing…
May 9 00:37:37 VERBOSE[6786] logger.c: – Called 2/3606394
May 9 00:37:37 DEBUG[6786] chan_zap.c: Exception on 18, channel 2
May 9 00:37:37 DEBUG[6786] chan_zap.c: Got event Hook Transition Complete(12) on channel 2 (index 0)
May 9 00:37:39 DEBUG[6786] chan_zap.c: Exception on 18, channel 2
May 9 00:37:39 DEBUG[6786] chan_zap.c: Got event Dial Complete(9) on channel 2 (index 0)
May 9 00:37:39 DEBUG[6786] chan_zap.c: Enabled echo cancellation on channel 2
May 9 00:37:39 DEBUG[6786] chan_zap.c: Engaged echo training on channel 2
May 9 00:37:41 DEBUG[6786] chan_zap.c: Exception on 18, channel 2
May 9 00:37:41 DEBUG[6786] chan_zap.c: Got event Dial Complete(9) on channel 2 (index 0)
May 9 00:37:41 DEBUG[6786] chan_zap.c: Echo cancellation already on
May 9 00:37:41 DEBUG[6786] chan_zap.c: Done dialing, but waiting for progress detection before doing more…
May 9 00:37:44 DEBUG[2855] chan_sip.c: Auto destroying call ‘c0a81175-13c4-14-531b-420f’
May 9 00:37:47 DEBUG[6786] chan_zap.c: Hangup: channel: 2 index = 0, normal = 18, callwait = -1, thirdcall = -1
May 9 00:37:47 DEBUG[6786] chan_zap.c: disabled echo cancellation on channel 2
May 9 00:37:47 DEBUG[6786] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/2-1
May 9 00:37:47 DEBUG[6786] chan_zap.c: Updated conferencing on 2, with 0 conference users
May 9 00:37:47 VERBOSE[6786] logger.c: – Hungup ‘Zap/2-1’
May 9 00:37:47 DEBUG[6786] app_dial.c: Exiting with DIALSTATUS=CANCEL.
May 9 00:37:47 VERBOSE[6786] logger.c: == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on ‘SIP/201-ef3c’ in macro ‘dialout-trunk’
May 9 00:37:47 VERBOSE[6786] logger.c: == Spawn extension (macro-dialout-trunk, s, 14) exited non-zero on ‘SIP/201-ef3c’
May 9 00:37:47 DEBUG[6786] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
May 9 00:37:47 DEBUG[6786] 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-05-09 00:35:29’,’“Chris” <201>’,‘201’,‘3606394’,‘from-internal’, ‘SIP/201-ef3c’,‘Zap/2-1’,‘Dial’,‘ZAP/2/3606394|120|W’,138,0,‘NO ANSWER’,3,’’,‘1147149329.66’)
May 9 00:37:47 DEBUG[6786] chan_sip.c: update_call_counter(201) - decrement call limit counter
May 9 00:37:47 DEBUG[2855] chan_sip.c: Stopping retransmission on ‘8057be1c-c0a81169-13c4-4460c4ce-6cdf162-5b04@192.168.21.5’ of Response 2: Match Found
May 9 00:38:08 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:38:18 DEBUG[2855] chan_sip.c: Auto destroying call '42063bc21386e7113703a07f35a3491f@192.168.21.5’
May 9 00:38:34 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:38:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:38:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:38:48 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:38:51 DEBUG[2855] chan_sip.c: Auto destroying call '46fe11d44ae1799d41e5f8a54f209b20@192.168.21.5’
May 9 00:39:25 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:39:29 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:39:35 DEBUG[2855] chan_sip.c: Auto destroying call '134749b76e1fb6df28edd50515190a60@192.168.21.5’
May 9 00:40:09 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:40:15 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:40:19 DEBUG[2855] chan_sip.c: Auto destroying call '46617b2d576e9d5167a82b2f6d005193@192.168.21.5’
May 9 00:40:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:40:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:40:50 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:40:52 DEBUG[2855] chan_sip.c: Auto destroying call '1d5f544537cdceaa79d40a6d0d5af17e@192.168.21.5’
May 9 00:41:05 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:41:31 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:41:36 DEBUG[2855] chan_sip.c: Auto destroying call '68d4ffa716e3e7915de1f96528c37303@192.168.21.5’
May 9 00:41:55 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:42:11 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:42:20 DEBUG[2855] chan_sip.c: Auto destroying call '56d9f28520b3499133fa9348221a4a27@192.168.21.5’
May 9 00:42:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:42:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:42:46 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:42:52 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:42:53 DEBUG[2855] chan_sip.c: Auto destroying call '4f2d32f877962a533df7a581156642d2@192.168.21.5’
May 9 00:43:32 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:43:36 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:43:37 DEBUG[2855] chan_sip.c: Auto destroying call '0751ddc00125e82d0e80d9ab52df6ba8@192.168.21.5’
May 9 00:44:13 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:44:21 DEBUG[2855] chan_sip.c: Auto destroying call '73951f5a442149122cf648b943da304b@192.168.21.5’
May 9 00:44:26 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:44:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:44:41 DEBUG[3224] manager.c: Manager received command 'Command’
May 9 00:44:54 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:44:54 DEBUG[2855] chan_sip.c: Auto destroying call '07cfd2d2514f3a3528292cbb48dc764c@192.168.21.5’
May 9 00:45:17 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81175-13c4-14-531b-420f’
May 9 00:45:35 DEBUG[2855] chan_sip.c: Auto destroying call 'c0a81169-13c4-445f06f6-6743-229e’
May 9 00:45:38 DEBUG[2855] chan_sip.c: Auto destroying call '0055dd0d15640fb5649142ad5c276d01@192.168.21.5’
May 9 00:45:38 DEBUG[2855] chan_sip.c: Auto destroying call '0055dd0d15640fb5649142ad5c276d[/quote]

I am having the same issue, have you resolved yours? This is the only thing that jumps out at me from my logs…

[code]May 10 12:55:24 DEBUG[13301] chan_zap.c: Requested indication -1 on channel Zap/2-1

May 10 12:56:46 DEBUG[13301] chan_zap.c: Exception on 21, channel 2
May 10 12:56:46 DEBUG[13301] chan_zap.c: Got event On hook(1) on channel 2 (index 0)
May 10 12:56:46 DEBUG[13301] chan_zap.c: disabled echo cancellation on channel 2
May 10 12:56:46 DEBUG[13301] channel.c: Didn’t get a frame from channel: Zap/2-1[/code]

anyone have any idea as to why these errors are coming up, and if they have caused the call to drop?

Thanks for any help!


#3

Do what it says over and over

May 9 00:37:37 WARNING[6786] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source.