Page 1 of 1

Slow response when dialing manually

PostPosted: Fri Mar 02, 2007 3:32 pm
by spinto
when I click the dial button it takes a long time to do the actual dialing. I have apache and mysql running on one server and asterisk running on the other. I have only one sip phone connected and logged on with a T1 connection. After some testing I found that saving to the db was taking the longest. As soon as it saved it immediately dialed. I know this becuase I uncommented the alert() command in basic_originate_call function.

Everything seems to be running slow including logging on, using the predictive dialer. I'm thinking it's a problem with apache but not sure. I did a clean install of everyting so nothing else is running. Based on performance monitor everything seems normal. I'm running CentOS 4.4 and the latest version of everything else.

PostPosted: Fri Mar 02, 2007 10:33 pm
by Op3r
check the time of those server if they are synchronized.

PostPosted: Fri Mar 02, 2007 11:01 pm
by mflorell
What is the loadavg when this happens?

Can you post some Asterisk CLI output?

Try any Linux distro except for CentOS or RHEL

PostPosted: Mon Mar 12, 2007 9:45 am
by spinto
Here is a copy of the output when I run loadavg:

0.00 0.00 0.00 1/78 8228

I have reinstalled Vicidial and MySQL on a slackware box. I followed the instruction and am still having a problem. The page seems to take a while when saving to vicidial_manager table. I'm not sure if it's because the page manager_send is taking a long time to execute or if it's because of the insert query. As soon as manager_send sends me the response text the phone starts dialing.

Also there is no error in the CLI output. Everything seems normal.

Verbosity is at least 10
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 192.168.1.239
> Channel SIP/2002-09be14f8 was answered.
-- Executing AGI("SIP/2002-09be14f8", "call_log") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log
-- AGI Script call_log completed, returning 0
-- Executing Dial("SIP/2002-09be14f8", "Zap/g1/17862641355|60|Ttr") in new stack
-- Requested transfer capability: 0x00 - SPEECH
-- Called g1/17862641355
-- Zap/1-1 is proceeding passing it to SIP/2002-09be14f8
-- Zap/1-1 is making progress passing it to SIP/2002-09be14f8
== Manager 'sendcron' logged off from 192.168.1.239
-- Zap/1-1 is ringing
-- Zap/1-1 answered SIP/2002-09be14f8
-- Channel 0/1, span 1 got hangup request
-- Hungup 'Zap/1-1'
== Spawn extension (outbound-level2, 917862641355, 2) exited non-zero on 'SIP/2002 -09be14f8'
-- Executing DeadAGI("SIP/2002-09be14f8", "call_log") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log
-- AGI Script call_log completed, returning 0
-- Executing DeadAGI("SIP/2002-09be14f8", "VD_hangup--HVcauses--PRI-----NODEBUG- ----16-----ANSWER-----17-----3)") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup--HVcauses--PRI-----NO DEBUG-----16-----ANSWER-----17-----3)
-- AGI Script VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----17----- 3) completed, returning 0


How do I go about turning on and monitoring debugging with Vicidial. Also I'm only trying to manually right now through astguiclient. I haven't even tried the predictive dialer yet.

action_process log

PostPosted: Mon Mar 12, 2007 12:47 pm
by spinto
|
2007-03-12 10:41:27|SQL_QUERY|UPDATE vicidial_manager set status='SENT' where man_id='36'||
2007-03-12 10:47:05|SQL_QUERY|SELECT * FROM vicidial_manager where server_ip = '192.168.1.79' and status = 'QUEUE' order by entry_date desc limit 1||
2007-03-12 10:47:05|----BEGIN NEW COMMAND----
Action: Originate
Channel: SIP/2265
Context: default
Exten: 917862641355
Priority: 1
Callerid: "DOagcW11737144026666" <555555555>

----END NEW COMMAND----
|
2007-03-12 10:47:05|SQL_QUERY|UPDATE vicidial_manager set status='SENT' where man_id='37'||
2007-03-12 10:50:20|SQL_QUERY|SELECT * FROM vicidial_manager where server_ip = '192.168.1.79' and status = 'QUEUE' order by entry_date desc limit 1||
2007-03-12 10:50:20|----BEGIN NEW COMMAND----
Action: Originate
Channel: SIP/2265
Context: outbound-level2
Exten: 917862641355
Priority: 1
Callerid: "DOagcW11737145996666" <555555555>

----END NEW COMMAND----
|
2007-03-12 10:50:20|SQL_QUERY|UPDATE vicidial_manager set status='SENT' where man_id='38'||
2007-03-12 10:51:59|SQL_QUERY|SELECT * FROM vicidial_manager where server_ip = '192.168.1.79' and status = 'QUEUE' order by entry_date desc limit 1||
2007-03-12 10:51:59|----BEGIN NEW COMMAND----
Action: Originate
Channel: SIP/2265
Context: outbound-level2
Exten: 917862641355
Priority: 1
Callerid: "DOagcW11737146376666" <555555555>

----END NEW COMMAND----

PostPosted: Mon Mar 12, 2007 11:26 pm
by mflorell
How many seconds from login to your phone ringing?

Are you using "skip-lookups" in MySQL?

PostPosted: Tue Mar 13, 2007 9:18 am
by spinto
It seems to vary from several seconds to a minute or so. It's never instant. Looking at the log I posted it looks like it's updating then the select query appears a minute or more later.

I don't know what skip-lookups is and how to enable/disable it. I'll do research on that one and get back to you.

PostPosted: Tue Mar 13, 2007 11:38 am
by spinto
I wasn't able to find anything out about skip-lookups. Let me know what that is and how to check it.

PostPosted: Tue Mar 13, 2007 12:46 pm
by spinto
Matt you asked me how many seconds between login and the phone ringing. I'm not using vicidial. I'm doing a manual dial from astguiclient.php. I haven't tried vicidial yet.

PostPosted: Tue Mar 13, 2007 3:20 pm
by mflorell
in your /etc/my.cnf file, add the following line after the [mysqld] section:

skip-name-resolve



Then shutdown and restart MySQL

PostPosted: Tue Mar 13, 2007 4:25 pm
by spinto
I put that line there but no luck. There's still a delay. I have the following setup:

MySQL & Dialer
Dell P4
512 MB

Asterisk
Dell P4
512 MB

I wanted to mention the above in case that has something to do with the delay I'm seeing. I only have one sip phone hooked up to it. It's a test system before we go with the real deal. I did try vicidial and was running into the same delay. When I use the originate command from a PHP page my phone rings right away and as soon as I pick it up the called party begins to ring instantly.

PostPosted: Tue Mar 13, 2007 5:12 pm
by mflorell
have you tried using mtop to see mysql queries as they execute and see what that problem may be?

PostPosted: Tue Mar 13, 2007 11:47 pm
by enjay
I've seen this with DNS before..

add a host record for the asterisk server on the mysql system.

On the MySQL Server vi /etc/hosts

in that file put

IP.address.of.asteriskserver hostname_of_asterisk_server

save that file and then try again.


more specifically if you try to connect to the mysql server from the asterisk box via "mysql -u username -p -h ip.of.sql.system" and after you put the password in when prompted if it hangs there for any duration of time "5-10 seconds" before providing the mysql> prompt this is typically due to DNS (at least in my experience).

Give that a shot and let us know if it helps..

NOTE: I see Matt said to put in skip-name-resolve which should also resolve this though I've put that in and not had it work before while adding the host entry has.

PostPosted: Wed Mar 14, 2007 8:51 am
by spinto
There's still a delay. I logged onto mysql instantly from the asterisk server. I don't think it's a problem with the machines communicating. The delay occurs when a manual call is made through astguiclient.php. Once I'm on the call I get the "call details" at the top of the page with options such as hangup, record, xfer. Those call details come as soon as the called party picks up and goes away as soon as the call is disconnected.

Likewise when a call comes in it gets displayed right away. When clicking the hangup link in the call details it doesn't hangup. I'm thinking there's a delay there as well.

PostPosted: Wed Mar 14, 2007 9:40 am
by mflorell
Can you post Asterisk CLI output when this happens?

PostPosted: Wed Mar 14, 2007 10:56 am
by spinto
I installed and ran mtop. The queries running are not displaying on the screen. All I see is:

load average: 0.22, 0.06, 0.01 mysqld 4.0.27-log up 1 day(s), 21:11 hrs
11 threads: 1 running, 0 cached. Queries/slow: 4.8M/0 Cache Hit: 99.98%
Opened tables: 8 RRN: 200.6K TLW: 0 SFJ: 0 SMP: 0 QPS: 15

ID USER HOST DB TIME COMMAND STATE INFO
30536 root localhost Query show full processlist

CLI output

PostPosted: Wed Mar 14, 2007 11:07 am
by spinto
When manual dialing (took 7 seconds for the phone to ring)

Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 192.168.1.239
> Channel SIP/2265-09be1910 was answered.
-- Executing AGI("SIP/2265-09be1910", "call_log") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log
-- AGI Script call_log completed, returning 0
-- Executing Dial("SIP/2265-09be1910", "Zap/g1/17862641355|60|Ttr") in new stack
-- Requested transfer capability: 0x00 - SPEECH
-- Called g1/17862641355
-- Zap/1-1 is proceeding passing it to SIP/2265-09be1910
-- Zap/1-1 is making progress passing it to SIP/2265-09be1910
== Manager 'sendcron' logged off from 192.168.1.239
-- Zap/1-1 is ringing
-- Zap/1-1 answered SIP/2265-09be1910
-- Hungup 'Zap/1-1'
== Spawn extension (outbound-level2, 917862641355, 2) exited non-zero on 'SIP/2265-09be1910'
-- Executing DeadAGI("SIP/2265-09be1910", "call_log") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log
-- AGI Script call_log completed, returning 0
-- Executing DeadAGI("SIP/2265-09be1910", "VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----15-----8)") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----15-----8)
-- AGI Script VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----15-----8) completed, returning 0

when I receive an incoming call

-- Accepting call from '3054389696' to '8668056503' on channel 0/1, span 1
-- Executing Answer("Zap/1-1", "") in new stack
-- Executing Dial("Zap/1-1", "SIP/2265||Tto") in new stack
-- Called 2265
-- SIP/2265-09be1910 is ringing
-- SIP/2265-09be1910 answered Zap/1-1
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 192.168.1.239
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 192.168.1.239
== Manager 'sendcron' logged off from 192.168.1.239
== Manager 'sendcron' logged off from 192.168.1.239
-- Channel 0/1, span 1 got hangup request
== Spawn extension (incoming, 8668056503, 2) exited non-zero on 'Zap/1-1'
-- Hungup 'Zap/1-1'

PostPosted: Wed Mar 14, 2007 11:47 am
by mflorell
What kind of card do you have that Zap 1 goes through?

PostPosted: Wed Mar 14, 2007 11:56 am
by spinto
Currently we're using Digium Wildcard TE207P Dual T1 / E1 PCI Card Octasic DSP Echo Cancellation.

PostPosted: Wed Mar 14, 2007 12:16 pm
by mflorell
What kind of T1/E1 circuit are you using with this card? (zapata.conf/zaptel.conf settings)

PostPosted: Wed Mar 14, 2007 12:51 pm
by spinto
Zaptel.conf

span=1,1,0,esf,b8zs
loadzone=us
defaultzone=us
bchan=1-23
dchan=24

span=2,1,0,esf,b8zs
loadzone=us
defaultzone=us
bchan=25-47
dchan=48

Zapata.conf
[trunkgroups]
[channels]
signalling=pri_cpe
switchtype=dms100
usecallerid=yes
hidecallerid=no
callerid="Some Company" <800-877-7777>
context=blah
language=en
rxgain=0.0
txgain=0.0

group=1
context=incoming
channel => 1-23

group=2
context=incoming
channel => 25-47

PostPosted: Wed Mar 14, 2007 10:54 pm
by mflorell
What version of astguiclient are you using? The Asterisk CLI output doesn't look right.

PostPosted: Wed Mar 14, 2007 11:34 pm
by gerski
Code: Select all
span=1,1,0,esf,b8zs
loadzone=us
defaultzone=us
bchan=1-23
dchan=24


are you using T1 or E1? T1 doesn't used dchannel..
if you use E1 use this config instead:
Code: Select all
span=1,1,0,esf,b8zs
loadzone=us
defaultzone=us
bchan=1-15,17-31
dchan=16

if you use T1:
Code: Select all
span=1,1,0,esf,b8zs
loadzone=us
defaultzone=us
bchan=1-24

PostPosted: Wed Mar 14, 2007 11:42 pm
by mflorell
PRI T1s do use a D-channel, RBS T1s do not.

PostPosted: Wed Mar 14, 2007 11:44 pm
by gerski
ok sorry.. my mistake :o

PostPosted: Thu Mar 15, 2007 7:35 am
by spinto
I'm not sure of the version. I just downloaded it about 2 weeks ago so I'm assuming it's the current version. Also it is a T1 PRI.

PostPosted: Thu Mar 15, 2007 7:42 am
by spinto
I found the version. Its:

Version: 2.0.1
Build: 60829-1528

PostPosted: Mon Mar 26, 2007 3:55 pm
by spinto
I went away for a week, came back and moved the servers to a different location in the same building. For some reason it seems to be working okay. I'm having an issue with it hanging up after the person answers with autodialing but I'll post that elsewhere.

Thanks.