vicidial.org

VICIDIAL astGUIclient discussion forum

Skip to content

Vicidial.org Home      Vicidial Forum      Vicidial Wiki      Vicidial Issue Tracker      astGUIclient Project Page


fastagi hangs

All installation and configuration problems and questions

Moderators: gerski, enjay, williamconley, Op3r, Staydog, gardo, mflorell, MJCoate, mcargile, Kumba, Michael_N

Post a reply

fastagi hangs

Postby Op3r » Tue Jun 05, 2007 9:14 pm

Hi

I am having a very strange issue about the fastagi. When I dial which goes to fastagi first it will take time before it dials the number.

here's the log

Connected to Asterisk 1.2.17 currently running on mylene-asterisk (pid = 7023)
Verbosity was 33 and is now 35
-- Executing AGI("SIP/2111-09a815e8", "agi://127.0.0.1:4577/call_log") in new stack
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 10.10.200.80
== Manager 'sendcron' logged off from 10.10.200.80
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 10.10.200.80
== Manager 'sendcron' logged off from 10.10.200.80
-- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0
-- Executing Dial("SIP/2111-09a815e8", "SIP/sipprovider/18005551212||tTo") in new stack
-- Called sipprovider/18005551212
-- SIP/sipprovider-09afd0c8 is making progress passing it to SIP/2111-09a815e8
Jun 5 22:09:54 NOTICE[9228]: rtp.c:331 process_rfc3389: Comfort noise support incomplete in Asterisk (RFC 3389). Please turn off on client if possible. Client IP: 121.97.195.236
-- SIP/sipprovider-09afd0c8 answered SIP/2111-09a815e8
== Spawn extension (default, 918005551212, 2) exited non-zero on 'SIP/2111-09a815e8'
-- Executing DeadAGI("SIP/2111-09a815e8", "agi://127.0.0.1:4577/call_log") in new stack

here's the fastagi log

2007-06-05 22:12:17|TEST_VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++
2007-06-05 22:12:17|TEST_VDfastAGI|begin|Perl Environment Dump:
2007-06-05 22:12:17|TEST_VDfastAGI|begin|0|--debug
2007-06-05 22:12:17|TEST_VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|0|||)|
2007-06-05 22:12:17|TEST_VDfastAGI|begin|AGI Environment Dump:
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- accountcode =
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- callerid = 2111
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- calleridname = Edwin
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- callingani2 = 0
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- callingpres = 0
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- callingtns = 0
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- callington = 0
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- channel = SIP/2111-09a815e8
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- context = default
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- dnid = 918005551212
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- enhanced = 0.0
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- extension = h
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- language = en
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- network = yes
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- network_script = call_log
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- priority = 1
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- rdnis = unknown
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- request = agi://127.0.0.1:4577/call_log
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- type = SIP
2007-06-05 22:12:17|TEST_VDfastAGI|begin| -- uniqueid = 1181095870.15
2007-06-05 22:12:17|TEST_VDfastAGI|begin|AGI Variables: |1181095870.15|SIP/2111-09a815e8|h|SIP|2111|
2007-06-05 22:12:17|TEST_VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log|call_log|END|
2007-06-05 22:12:17|TEST_VDfastAGI|call_log||CALL HUNG UP|
2007-06-05 22:12:17|TEST_VDfastAGI|call_log||1181095870.1500001|1181095870|
2007-06-05 22:12:17|TEST_VDfastAGI|call_log|QUERY done: start time = 1181095870 | sec: 67 | min: 1.12 |
2007-06-05 22:12:17|TEST_VDfastAGI|call_log||UPDATE call_log set end_time='2007-06-05 22:12:17',end_epoch='1181095937',length_in_sec=67,length_in_min=' 1.12' where uniqueid='1181095870.15'|
2007-06-05 22:12:17|TEST_VDfastAGI|call_log||DELETE from live_inbound where uniqueid='1181095870.15' and server_ip='10.10.200.80'|
2007-06-05 22:12:17|TEST_VDfastAGI|call_log|+++++ CALL LOG HUNGUP: |1181095870.15|SIP/2111-09a815e8|h|2007-06-05 22:12:17|min: 1.12|
2007-06-05 22:12:23|TEST_VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++
2007-06-05 22:12:23|TEST_VDfastAGI|begin|Perl Environment Dump:
2007-06-05 22:12:23|TEST_VDfastAGI|begin|0|--debug
2007-06-05 22:12:23|TEST_VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|16|ANSWER|7|4)|
2007-06-05 22:12:23|TEST_VDfastAGI|begin|AGI Environment Dump:
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- accountcode =
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- callerid = 2111
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- calleridname = Edwin
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- callingani2 = 0
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- callingpres = 0
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- callingtns = 0
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- callington = 0
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- channel = SIP/2111-09afd0c8
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- context = default
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- dnid = 918005551212
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- enhanced = 0.0
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- extension = 918005551212
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- language = en
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- network = yes
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- network_script = call_log
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- priority = 1
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- rdnis = unknown
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- request = agi://127.0.0.1:4577/call_log
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- type = SIP
2007-06-05 22:12:23|TEST_VDfastAGI|begin| -- uniqueid = 1181095943.17
2007-06-05 22:12:23|TEST_VDfastAGI|begin|AGI Variables: |1181095943.17|SIP/2111-09afd0c8|918005551212|SIP|2111|
2007-06-05 22:12:23|TEST_VDfastAGI|call_log|Process to run: |agi://127.0.0.1:4577/call_log|call_log|START|
2007-06-05 22:12:23|TEST_VDfastAGI|call_log|+++++ CALL LOG START : 2007-06-05 22:12:23
2007-06-05 22:12:23|TEST_VDfastAGI|call_log||INSERT INTO call_log (uniqueid,channel,channel_group,type,server_ip,extension,number_dialed,start_time,start_epoch,end_time,end_epoch,length_in_sec,length_in_min,caller_code) values('1181095943.17','SIP/2111','Outbound Local 800','SIP','10.10.200.80','2111','8005551212','2007-06-05 22:12:23','1181095943','','','','','2111')|
2007-06-05 22:12:51|TEST_VDfastAGI|begin|+++++++++++++++++ FastAGI Start ++++++++++++++++++++++++++++++++++++++++
2007-06-05 22:12:51|TEST_VDfastAGI|begin|Perl Environment Dump:
2007-06-05 22:12:51|TEST_VDfastAGI|begin|0|--debug
2007-06-05 22:12:51|TEST_VDfastAGI|begin|URL HVcauses: |PRI|NODEBUG|16|ANSWER|16|13)|
2007-06-05 22:12:51|TEST_VDfastAGI|begin|AGI Environment Dump:
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- accountcode =
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- callerid = 2111
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- calleridname = Edwin
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- callingani2 = 0
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- callingpres = 0
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- callingtns = 0
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- callington = 0
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- channel = SIP/2111-09a815e8
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- context = default
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- dnid = 918005551212
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- enhanced = 0.0
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- extension = h
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- language = en
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- network = yes
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- network_script = VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----16-----13)
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- priority = 2
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- rdnis = unknown
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- request = agi://127.0.0.1:4577/VD_hangup--HVcause ... -16-----13)
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- type = SIP
2007-06-05 22:12:51|TEST_VDfastAGI|begin| -- uniqueid = 1181095870.15
2007-06-05 22:12:51|TEST_VDfastAGI|begin|AGI Variables: |1181095870.15|SIP/2111-09a815e8|h|SIP|2111|
2007-06-05 22:12:51|TEST_VDfastAGI|VD_hangup|Process to run: |agi://127.0.0.1:4577/VD_hangup--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----16-----13)|VD_hangup|END|
2007-06-05 22:12:51|TEST_VDfastAGI|VD_hangup|DEBUG: NODEBUG
2007-06-05 22:12:51|TEST_VDfastAGI|VD_hangup|VD_hangup : 2111 SIP/2111-09a815e8 2 0
2007-06-05 22:12:51|TEST_VDfastAGI|VD_hangup||SELECT lead_id,callerid,campaign_id,alt_dial,stage,UNIX_TIMESTAMP(call_time) FROM vicidial_auto_calls where uniqueid = '1181095870.15' limit 1;|
2007-06-05 22:12:51|TEST_VDfastAGI|VD_hangup|VD hangup: no VDAC record found: 1181095870.15 21

any ideas?
Get paid for US outbound Toll Free calls. PM me.
Op3r
 
Posts: 1424
Joined: Wed Jun 07, 2006 7:53 pm
Location: Manila
Top

Postby Op3r » Tue Jun 05, 2007 10:12 pm

even when Im using the standard agi it is slow to dial. It goes to the agi first then wait for a couple of minutes then it dials :(

I was thinking if it is really possible that my agi files are corrupted but when I shut down the database server its fast :(

now I am thinking about reformatting the dbase server
Get paid for US outbound Toll Free calls. PM me.
Op3r
 
Posts: 1424
Joined: Wed Jun 07, 2006 7:53 pm
Location: Manila
Top

Postby mflorell » Wed Jun 06, 2007 8:51 pm

What's the MySQL load when this happens?

Have you done "show full processlist;" in Mysql while this happens?
mflorell
Site Admin
 
Posts: 18339
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida
Top

Postby Op3r » Wed Jun 06, 2007 10:23 pm

Im getting this

| 5769 | unauthenticated user | 10.10.200.80:34045 | NULL | Connect | NULL | login | NULL |
| 5770 | unauthenticated user | 10.10.200.80:34046 | NULL | Connect | NULL | login | NULL |
| 5771 | unauthenticated user | 10.10.200.80:34047 | NULL | Connect | NULL | login | NULL |
| 5772 | root | localhost | NULL | Query | 0 | NULL | show full processlist |


which is quite strange cos I never changed the password of cron on astguiclient.conf and on mysql :(
Get paid for US outbound Toll Free calls. PM me.
Op3r
 
Posts: 1424
Joined: Wed Jun 07, 2006 7:53 pm
Location: Manila
Top

Postby mflorell » Thu Jun 07, 2007 8:57 pm

do you have skip-lookups in your MySQL startup script or your my.cnf file?
mflorell
Site Admin
 
Posts: 18339
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida
Top

Postby Op3r » Fri Jun 08, 2007 12:29 am

none should I put that in?

I installed mysql-server using yum then just did chkconfig mysqld on on a Centos box
Get paid for US outbound Toll Free calls. PM me.
Op3r
 
Posts: 1424
Joined: Wed Jun 07, 2006 7:53 pm
Location: Manila
Top

Postby mflorell » Fri Jun 08, 2007 5:19 am

If you don't have "skip-name-resolve" in the my.cnf or " --skip-name-resolve" as a CLI startup option then you may be doing DNS lookups for every login to MySQL. This can cause delay problems.

Try adding one of those and see what happens.
mflorell
Site Admin
 
Posts: 18339
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida
Top
Post a reply

Return to Support

Who is online

Users browsing this forum: No registered users and 249 guests

Powered by phpBB® Forum Software © phpBB Group