vicidial.org

by **cyberahmed** » Wed Jan 05, 2011 9:14 am

Hi Everyone,

I have install Goautodial ver. 2.2.1-237 BUILD: 100510-2015 (install from CD ISO based on Centos 5.5. System). System works properly width small problem of manual call. For each call when user made call manually (width succes as well) system showing message after 50 sec about timeout "Please contact width administrator ..." It is not a big problem for me - is bigger for users

Bigger problem is width hooper becouse he calling really faster on outside via SIP trunk - it's chacked on my phone, but after answer form outside system not transferred this call to agent - but connect to dialed number is active durning to 10-12 sec and after then system hung up it.
Real-Time Main Report showing haw many calls ringing, haw many users waiting on call, agent state (usualy Redy or waiting on call).
Ofcourse system load lids to hooper, hooper calling (is checked) campagin is active users as well, asterisk ver. 1.4.27.1 running (users calling maually) users using x-lite application on workstations.
Hopper config on campagin:
Hopper Level: 20
Force Reset of Hopper: N
Dial Method: ADAP_AVERAGE
Auto Dial Level: 3
Drop Percentage Limit: 3
Maximum Adapt Dial Level: 3

screen -ls
There are screens on:
3068.ASTVDauto (Detached)
3062.ASTsend (Detached)
3077.ASTfastlog (Detached)
3054.goautodial_d (Detached)
2821.asterisk (Detached)
2816.astshell20110104190019 (Detached)
3071.ASTVDremote (Detached)
3065.ASTlisten (Detached)
21625.ASTVDadapt (Detached)
3059.ASTupdate (Detached)
10 Sockets in /var/run/screen/S-root.

Thank's a lot for suggestions where is stake ... Maybe I must change small option someware in system, campagin, phones, users, server settings, but I don't now where

by **williamconley** » Wed Jan 05, 2011 11:00 am

1) "Version 2.2.1" is VICIDIAL not GoAutoDial, please list your Installer version (from the install CD, probably 2.0) and remember that GoAutoDial is JUST the installer, not the software. The name of this software package is VICIDIAL (makes googling work a lot better if you have the right name of the software!)

2) Did you change the IP address of your server after you installed? did you run the update server ip script in /usr/share/astguiclient after changing it if you did?

by **cyberahmed** » Thu Jan 06, 2011 1:50 pm

ad 1. Right! it was install from goautodial-ce-2.0-final.iso

Thank's for info about version ...

ad. 2 Yes I run this script (after your post again) and reboot server but still nothing

.

FYI first time hooper script was starting manually because after installation he not start automatically. Maybe I starting wrong hooper script?

by **williamconley** » Thu Jan 06, 2011 2:22 pm

hopper script runs every minute. and stops when it runs out of things to do (usually after a second or so)

Code: Select all: crontab -e

will show you a list of your expected running scripts.

however, that is not likely the issue as the only purpose of the hopper is to load leads for dialing.

show your asterisk CLI from a "problem moment" when one of your calls is answered but does not get transferred to an agent (show cli from Answer to Hangup, please do not post 3000 lines of code, and do not use the dialer for anything else during this period, JUST for this one test so our CLI output does not have too much extraneous information)

by **cyberahmed** » Thu Jan 06, 2011 2:52 pm

Code: Select all: [root@cc01-leg-dial-pbx ~]# crontab -e * * * * * /usr/share/astguiclient/AST_manager_kill_hung_congested.pl ### updater for voicemail * * * * * /usr/share/astguiclient/AST_vm_update.pl ### updater for conference validator * * * * * /usr/share/astguiclient/AST_conf_update.pl ### flush queue DB table every hour for entries older than 1 hour 11 * * * * /usr/share/astguiclient/AST_flush_DBqueue.pl -q ### fix the vicidial_agent_log once every hour and the full day run at night 33 * * * * /usr/share/astguiclient/AST_cleanup_agent_log.pl 50 0 * * * /usr/share/astguiclient/AST_cleanup_agent_log.pl --last-24hours ## uncomment below if using QueueMetrics #*/5 * * * * /usr/share/astguiclient/AST_cleanup_agent_log.pl --only-qm-live-call-check ## uncomment below if using Vtiger 1 1 * * * /usr/share/astguiclient/Vtiger_optimize_all_tables.pl --quiet ### updater for VICIDIAL hopper * * * * * /usr/share/astguiclient/AST_VDhopper.pl -q ### adjust the GMT offset for the leads in the vicidial_list table 1 1 * * * /usr/share/astguiclient/ADMIN_adjust_GMTnow_on_leads.pl --debug ### reset several temporary-info tables in the database 2 1 * * * /usr/share/astguiclient/AST_reset_mysql_vars.pl ### optimize the database tables within the asterisk database 3 1 * * * /usr/share/astguiclient/AST_DB_optimize.pl ## adjust time on the server with ntp 30 * * * * /usr/sbin/ntpdate -u pool.ntp.org 2>/dev/null 1>&2 ### VICIDIAL agent time log weekly and daily summary report generation 2 0 * * 0 /usr/share/astguiclient/AST_agent_week.pl 22 0 * * * /usr/share/astguiclient/AST_agent_day.pl ### VICIDIAL campaign export scripts (OPTIONAL) #32 0 * * * /usr/share/astguiclient/AST_VDsales_export.pl #42 0 * * * /usr/share/astguiclient/AST_sourceID_summary_export.pl ### remove old recordings more than 7 days old #24 0 * * * /usr/bin/find /var/spool/asterisk/monitorDONE -maxdepth 2 -type f -mtime +7 -print | xargs rm -f ### roll logs monthly on high-volume dialing systems #30 1 1 * * /usr/share/astguiclient/ADMIN_archive_log_tables.pl ### remove old vicidial logs and asterisk logs more than 2 days old 28 0 * * * /usr/bin/find /var/log/astguiclient -maxdepth 1 -type f -mtime +2 -print | xargs rm -f 29 0 * * * /usr/bin/find /var/log/asterisk -maxdepth 3 -type f -mtime +2 -print | xargs rm -f 30 0 * * * /usr/bin/find / -maxdepth 1 -name "screenlog.0*" -mtime +4 -print | xargs rm -f ### keepalive script for GoAutoDial processes * * * * * /usr/share/goautodial/keepalive_goautodial.pl ### Logs cleanup for GoAutoDial 8 1 * * * /usr/share/goautodial/go_clean.pl

Behind logs from asterisk CLI - only for test from one user:

Code: Select all: [Jan 6 20:37:41] > Channel SIP/cc120-0000001b was answered. [Jan 6 20:37:41] -- Executing [8600051@default:1] MeetMe("SIP/cc120-0000001b", "8600051|F") in new stack [Jan 6 20:37:41] == Parsing '/etc/asterisk/meetme.conf': [Jan 6 20:37:41] Found [Jan 6 20:37:41] == Parsing '/etc/asterisk/meetme-vicidial.conf': [Jan 6 20:37:41] Found [Jan 6 20:37:41] -- Created MeetMe conference 1023 for conference '8600051' [Jan 6 20:37:41] -- <SIP/cc120-0000001b> Playing 'conf-onlyperson' (language 'en') [Jan 6 20:37:41] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 6 20:37:53] == Parsing '/etc/asterisk/manager.conf': [Jan 6 20:37:53] Found [Jan 6 20:37:53] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 6 20:37:53] -- Executing [9505755477@default:1] AGI("Local/9505755477@default-8bd5,2", "agi://127.0.0.1:4577/call_log") in new stack [Jan 6 20:37:53] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 6 20:37:53] -- Executing [9505755477@default:2] Set("Local/9505755477@default-8bd5,2", "CALLERID(all)=0224943449 <0224943449>") in new stack [Jan 6 20:37:53] -- Executing [9505755477@default:3] Dial("Local/9505755477@default-8bd5,2", "SIP/sip_comptel_01/505755477||tTor") in new stack [Jan 6 20:37:53] -- Called sip_comptel_01/505755477 [Jan 6 20:37:53] == Parsing '/etc/asterisk/manager.conf': [Jan 6 20:37:53] Found [Jan 6 20:37:53] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 6 20:37:53] -- Executing [9505755477@default:1] AGI("Local/9505755477@default-5caf,2", "agi://127.0.0.1:4577/call_log") in new stack [Jan 6 20:37:53] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 6 20:37:53] -- Executing [9505755477@default:2] Set("Local/9505755477@default-5caf,2", "CALLERID(all)=0224943449 <0224943449>") in new stack [Jan 6 20:37:53] -- Executing [9505755477@default:3] Dial("Local/9505755477@default-5caf,2", "SIP/sip_comptel_01/505755477||tTor") in new stack [Jan 6 20:37:53] -- Called sip_comptel_01/505755477 [Jan 6 20:37:53] == Parsing '/etc/asterisk/manager.conf': [Jan 6 20:37:53] Found [Jan 6 20:37:53] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 6 20:37:53] -- Executing [9505755477@default:1] AGI("Local/9505755477@default-d27e,2", "agi://127.0.0.1:4577/call_log") in new stack [Jan 6 20:37:53] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 6 20:37:53] -- Executing [9505755477@default:2] Set("Local/9505755477@default-d27e,2", "CALLERID(all)=0224943449 <0224943449>") in new stack [Jan 6 20:37:53] -- Executing [9505755477@default:3] Dial("Local/9505755477@default-d27e,2", "SIP/sip_comptel_01/505755477||tTor") in new stack [Jan 6 20:37:53] -- Called sip_comptel_01/505755477 [Jan 6 20:37:54] == Refreshing DNS lookups. [Jan 6 20:37:54] -- SIP/sip_comptel_01-0000001d is making progress passing it to Local/9505755477@default-5caf,2 [Jan 6 20:37:54] -- SIP/sip_comptel_01-0000001e is making progress passing it to Local/9505755477@default-d27e,2 [Jan 6 20:37:54] -- Got SIP response 486 "Busy here" back from 62.87.244.35 [Jan 6 20:37:54] -- SIP/sip_comptel_01-0000001e is busy [Jan 6 20:37:54] == Everyone is busy/congested at this time (1:1/0/0) [Jan 6 20:37:54] -- Executing [9505755477@default:4] Hangup("Local/9505755477@default-d27e,2", "") in new stack [Jan 6 20:37:54] == Spawn extension (default, 9505755477, 4) exited non-zero on 'Local/9505755477@default-d27e,2' [Jan 6 20:37:54] -- Executing [h@default:1] DeadAGI("Local/9505755477@default-d27e,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----17-----BUSY----------") in new stack [Jan 6 20:37:54] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----17-----BUSY---------- completed, returning 0 [Jan 6 20:37:57] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 6 20:37:57] -- SIP/sip_comptel_01-0000001c is making progress passing it to Local/9505755477@default-8bd5,2 [Jan 6 20:37:57] -- SIP/sip_comptel_01-0000001c is ringing [Jan 6 20:37:59] -- SIP/sip_comptel_01-0000001c answered Local/9505755477@default-8bd5,2 [Jan 6 20:37:59] > Channel Local/9505755477@default-8bd5,1 was answered. [Jan 6 20:37:59] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 6 20:37:59] -- Executing [8368@default:1] Playback("Local/9505755477@default-8bd5,1", "sip-silence") in new stack [Jan 6 20:37:59] -- <Local/9505755477@default-8bd5,1> Playing 'sip-silence' (language 'en') [Jan 6 20:37:59] WARNING[12510]: file.c:1292 waitstream_core: Unexpected control subclass '-1' [Jan 6 20:37:59] -- Executing [h@default:1] DeadAGI("Local/9505755477@default-8bd5,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----6-----0") in new stack [Jan 6 20:37:59] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----6-----0 completed, returning 0 [Jan 6 20:37:59] == Spawn extension (default, 9505755477, 3) exited non-zero on 'Local/9505755477@default-8bd5,2' [Jan 6 20:37:59] -- Executing [8368@default:2] AGI("SIP/sip_comptel_01-0000001c", "agi://127.0.0.1:4577/call_log") in new stack [Jan 6 20:37:59] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 6 20:37:59] -- Executing [8368@default:3] AGI("SIP/sip_comptel_01-0000001c", "agi-VDAD_ALL_outbound.agi|NORMAL-----LB") in new stack [Jan 6 20:37:59] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi [Jan 6 20:38:00] -- Got SIP response 486 "Busy here" back from 62.87.244.35 [Jan 6 20:38:00] -- SIP/sip_comptel_01-0000001d is busy [Jan 6 20:38:00] == Everyone is busy/congested at this time (1:1/0/0) [Jan 6 20:38:00] -- Executing [9505755477@default:4] Hangup("Local/9505755477@default-5caf,2", "") in new stack [Jan 6 20:38:00] == Spawn extension (default, 9505755477, 4) exited non-zero on 'Local/9505755477@default-5caf,2' [Jan 6 20:38:00] -- Executing [h@default:1] DeadAGI("Local/9505755477@default-5caf,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----17-----BUSY----------") in new stack [Jan 6 20:38:00] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----17-----BUSY---------- completed, returning 0 [Jan 6 20:38:00] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 6 20:38:02] == Parsing '/etc/asterisk/manager.conf': [Jan 6 20:38:02] Found [Jan 6 20:38:02] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 6 20:38:02] == Parsing '/etc/asterisk/manager.conf': [Jan 6 20:38:02] Found [Jan 6 20:38:02] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 6 20:38:02] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 6 20:38:07] == Parsing '/etc/asterisk/manager.conf': [Jan 6 20:38:07] Found [Jan 6 20:38:07] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 6 20:38:07] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 6 20:38:07] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 6 20:38:09] == Spawn extension (default, 8368, 3) exited non-zero on 'SIP/sip_comptel_01-0000001c' [Jan 6 20:38:09] -- Executing [h@default:1] DeadAGI("SIP/sip_comptel_01-0000001c", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0---------------") in new stack [Jan 6 20:38:09] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----0--------------- completed, returning 0

Anyway I have 187 package available to update in OS system, can do it?

by **williamconley** » Thu Jan 06, 2011 2:58 pm

CALLERID(all)=0224943449

can't use this. ever. callerid(num) can be used, but not name or all

you should post your carrier settings for this carrier.

by **cyberahmed** » Thu Jan 06, 2011 4:22 pm

Thanks for suggestion but it is problem width my sip provider. It must be set on CALLERID(all)=0224943449 <0224943449> otherwise doesn't work

- I got a busy on each call.

Tomorrow I put on system sip trunk form other sip provider which not needed caller id and try again of hope width success. Thanks a lot for yours support - I'm gonna go to bed

by **williamconley** » Thu Jan 06, 2011 5:48 pm

did you TRY callerid(num)?

did you try setting your CAMPAIGN callerid to 0224943449?

by **cyberahmed** » Fri Jan 07, 2011 3:42 am

Yes I trying both option (callerid(num) and campaign callerid) but still doesn't - only callerid(all) works

by **williamconley** » Fri Jan 07, 2011 9:03 am

temporarily, you could try the loopback method (will generate two calls instead of one, but you can control the callerid on the 2nd call!).

search extensions.conf for the loopback dialplan.

by **cyberahmed** » Fri Jan 07, 2011 2:59 pm

So I try loopbeck but actually I'm change the provider and situation is much better because on destination phone (who is calling from system) can hear "goodbye" and after then disconnect of course (after 10-12 sec).

Today I obsorve it another strange symptom because in place where usually is date time on agent interface (top part of) I have something like NaN-NaN-NaN NaN:NaN:NaN. Maybe it is proof on communication problem between server and client.

Behind logs form CLI testing call width hearing "goodbye".

Code: Select all: [Jan 7 20:46:44] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 7 20:46:44] -- Executing [9505755477@default:1] AGI("Local/9505755477@default-5e34,2", "agi://127.0.0.1:4577/call_log") in new stack [Jan 7 20:46:44] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 7 20:46:44] -- Executing [9505755477@default:2] Dial("Local/9505755477@default-5e34,2", "SIP/sip_comptel_02/505755477||tTor") in new stack [Jan 7 20:46:44] -- Called sip_comptel_02/505755477 [Jan 7 20:46:44] == Parsing '/etc/asterisk/manager.conf': [Jan 7 20:46:44] Found [Jan 7 20:46:44] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 7 20:46:44] -- Executing [9505755477@default:1] AGI("Local/9505755477@default-3228,2", "agi://127.0.0.1:4577/call_log") in new stack [Jan 7 20:46:44] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 7 20:46:44] -- Executing [9505755477@default:2] Dial("Local/9505755477@default-3228,2", "SIP/sip_comptel_02/505755477||tTor") in new stack [Jan 7 20:46:44] -- Called sip_comptel_02/505755477 [Jan 7 20:46:44] == Parsing '/etc/asterisk/manager.conf': [Jan 7 20:46:44] Found [Jan 7 20:46:44] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 7 20:46:44] -- Executing [9505755477@default:1] AGI("Local/9505755477@default-7eca,2", "agi://127.0.0.1:4577/call_log") in new stack [Jan 7 20:46:44] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 7 20:46:44] -- Executing [9505755477@default:2] Dial("Local/9505755477@default-7eca,2", "SIP/sip_comptel_02/505755477||tTor") in new stack [Jan 7 20:46:44] -- Called sip_comptel_02/505755477 [Jan 7 20:46:44] -- SIP/sip_comptel_02-00000004 is ringing [Jan 7 20:46:44] -- SIP/sip_comptel_02-00000005 is ringing [Jan 7 20:46:44] -- SIP/sip_comptel_02-00000006 is ringing [Jan 7 20:46:45] -- SIP/sip_comptel_02-00000004 is making progress passing it to Local/9505755477@default-5e34,2 [Jan 7 20:46:45] -- SIP/sip_comptel_02-00000004 is ringing [Jan 7 20:46:45] -- SIP/sip_comptel_02-00000004 is making progress passing it to Local/9505755477@default-5e34,2 [Jan 7 20:46:45] -- SIP/sip_comptel_02-00000006 is making progress passing it to Local/9505755477@default-7eca,2 [Jan 7 20:46:45] -- SIP/sip_comptel_02-00000006 is ringing [Jan 7 20:46:45] -- SIP/sip_comptel_02-00000006 is making progress passing it to Local/9505755477@default-7eca,2 [Jan 7 20:46:45] -- SIP/sip_comptel_02-00000005 is making progress passing it to Local/9505755477@default-3228,2 [Jan 7 20:46:45] -- SIP/sip_comptel_02-00000005 is ringing [Jan 7 20:46:45] -- SIP/sip_comptel_02-00000005 is making progress passing it to Local/9505755477@default-3228,2 [Jan 7 20:46:51] -- Got SIP response 486 "Busy Here" back from 79.133.193.90 [Jan 7 20:46:51] -- SIP/sip_comptel_02-00000005 is busy [Jan 7 20:46:51] == Everyone is busy/congested at this time (1:1/0/0) [Jan 7 20:46:51] -- Executing [9505755477@default:3] Hangup("Local/9505755477@default-3228,2", "") in new stack [Jan 7 20:46:51] == Spawn extension (default, 9505755477, 3) exited non-zero on 'Local/9505755477@default-3228,2' [Jan 7 20:46:51] -- Executing [h@default:1] DeadAGI("Local/9505755477@default-3228,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----17-----BUSY----------") in new stack [Jan 7 20:46:52] == Parsing '/etc/asterisk/manager.conf': [Jan 7 20:46:52] Found [Jan 7 20:46:52] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 7 20:46:52] -- Executing [9505755477@default:1] AGI("Local/9505755477@default-2cf2,2", "agi://127.0.0.1:4577/call_log") in new stack [Jan 7 20:46:52] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 7 20:46:52] -- Executing [9505755477@default:2] Dial("Local/9505755477@default-2cf2,2", "SIP/sip_comptel_02/505755477||tTor") in new stack [Jan 7 20:46:52] -- Called sip_comptel_02/505755477 [Jan 7 20:46:52] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----17-----BUSY---------- completed, returning 0 [Jan 7 20:46:52] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 7 20:46:52] -- SIP/sip_comptel_02-00000007 is ringing [Jan 7 20:46:52] -- SIP/sip_comptel_02-00000007 is making progress passing it to Local/9505755477@default-2cf2,2 [Jan 7 20:46:52] -- SIP/sip_comptel_02-00000007 is ringing [Jan 7 20:46:52] -- SIP/sip_comptel_02-00000007 is making progress passing it to Local/9505755477@default-2cf2,2 [Jan 7 20:46:54] -- Got SIP response 486 "Busy Here" back from 79.133.193.90 [Jan 7 20:46:54] -- SIP/sip_comptel_02-00000006 is busy [Jan 7 20:46:54] == Everyone is busy/congested at this time (1:1/0/0) [Jan 7 20:46:54] -- Executing [9505755477@default:3] Hangup("Local/9505755477@default-7eca,2", "") in new stack [Jan 7 20:46:54] == Spawn extension (default, 9505755477, 3) exited non-zero on 'Local/9505755477@default-7eca,2' [Jan 7 20:46:54] -- Executing [h@default:1] DeadAGI("Local/9505755477@default-7eca,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----17-----BUSY----------") in new stack [Jan 7 20:46:55] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----17-----BUSY---------- completed, returning 0 [Jan 7 20:46:55] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 7 20:46:55] -- SIP/sip_comptel_02-00000004 answered Local/9505755477@default-5e34,2 [Jan 7 20:46:55] > Channel Local/9505755477@default-5e34,1 was answered. [Jan 7 20:46:55] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 7 20:46:55] -- Executing [8368@default:1] Playback("Local/9505755477@default-5e34,1", "sip-silence") in new stack [Jan 7 20:46:55] -- <Local/9505755477@default-5e34,1> Playing 'sip-silence' (language 'en') [Jan 7 20:46:55] WARNING[3678]: file.c:1292 waitstream_core: Unexpected control subclass '-1' [Jan 7 20:46:55] -- Executing [h@default:1] DeadAGI("Local/9505755477@default-5e34,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----11-----0") in new stack [Jan 7 20:46:55] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----11-----0 completed, returning 0 [Jan 7 20:46:55] -- Executing [8368@default:2] AGI("SIP/sip_comptel_02-00000004", "agi://127.0.0.1:4577/call_log") in new stack [Jan 7 20:46:55] == Spawn extension (default, 9505755477, 2) exited non-zero on 'Local/9505755477@default-5e34,2' [Jan 7 20:46:55] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 7 20:46:55] -- Executing [8368@default:3] AGI("SIP/sip_comptel_02-00000004", "agi-VDAD_ALL_outbound.agi|NORMAL-----LB") in new stack [Jan 7 20:46:55] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi [Jan 7 20:46:58] -- Got SIP response 486 "Busy Here" back from 79.133.193.90 [Jan 7 20:46:58] -- SIP/sip_comptel_02-00000007 is busy [Jan 7 20:46:58] == Everyone is busy/congested at this time (1:1/0/0) [Jan 7 20:46:58] -- Executing [9505755477@default:3] Hangup("Local/9505755477@default-2cf2,2", "") in new stack [Jan 7 20:46:58] == Spawn extension (default, 9505755477, 3) exited non-zero on 'Local/9505755477@default-2cf2,2' [Jan 7 20:46:58] -- Executing [h@default:1] DeadAGI("Local/9505755477@default-2cf2,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----17-----BUSY----------") in new stack [Jan 7 20:46:59] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----17-----BUSY---------- completed, returning 0 [Jan 7 20:46:59] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 7 20:47:01] -- Playing 'ding' (escape_digits=) (sample_offset 0) [Jan 7 20:47:01] == Parsing '/etc/asterisk/manager.conf': [Jan 7 20:47:01] Found [Jan 7 20:47:01] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 7 20:47:01] == Parsing '/etc/asterisk/manager.conf': [Jan 7 20:47:01] Found [Jan 7 20:47:01] == Manager 'sendcron' logged on from 127.0.0.1 [Jan 7 20:47:01] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 7 20:47:02] ERROR[3678]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe [Jan 7 20:47:02] -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0 [Jan 7 20:47:02] -- Executing [8307@default:1] Answer("SIP/sip_comptel_02-00000004", "") in new stack [Jan 7 20:47:02] -- Executing [8307@default:2] Playback("SIP/sip_comptel_02-00000004", "vm-goodbye") in new stack [Jan 7 20:47:02] -- <SIP/sip_comptel_02-00000004> Playing 'vm-goodbye' (language 'en') [Jan 7 20:47:03] -- Executing [8307@default:3] Hangup("SIP/sip_comptel_02-00000004", "") in new stack [Jan 7 20:47:03] == Spawn extension (default, 8307, 3) exited non-zero on 'SIP/sip_comptel_02-00000004' [Jan 7 20:47:03] -- Executing [h@default:1] DeadAGI("SIP/sip_comptel_02-00000004", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack [Jan 7 20:47:03] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0

My outbound context:

Code: Select all: exten => _9XXXXXXXXX,1,AGI(agi://127.0.0.1:4577/call_log) exten => _9XXXXXXXXX,2,Dial(${siptrunk02}/${EXTEN:1},,tTor) exten => _9XXXXXXXXX,n,Hangup

[/img]

by **williamconley** » Fri Jan 07, 2011 3:07 pm

do me a favor, next time ONLY post the output from ONE CALL, not from several attempts. it looks like you tried the call several times but the line was busy, then you got through but there was no agent available so the call was dropped.

in future: use YOUR CELL PHONE as the test number so YOU can control the entire call and ONLY make one test call, and then post only one call worth of CLI output.

easier reading

by **cyberahmed** » Fri Jan 07, 2011 3:25 pm

Only one call answered and goodbye duration time is the same.

Code: Select all: [Jan 7 21:22:14] -- Executing [9505755477@default:1] AGI("Local/9505755477@default-fb1c,2", "agi://127.0.0.1:4577/call_log") in new stack [Jan 7 21:22:14] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 7 21:22:14] -- Executing [9505755477@default:2] Dial("Local/9505755477@default-fb1c,2", "SIP/sip_comptel_02/505755477||tTor") in new stack [Jan 7 21:22:14] -- Called sip_comptel_02/505755477 [Jan 7 21:22:14] -- SIP/sip_comptel_02-0000000e is ringing [Jan 7 21:22:14] -- SIP/sip_comptel_02-0000000e is making progress passing it to Local/9505755477@default-fb1c,2 [Jan 7 21:22:14] -- SIP/sip_comptel_02-0000000e is ringing [Jan 7 21:22:14] -- SIP/sip_comptel_02-0000000e is making progress passing it to Local/9505755477@default-fb1c,2 [Jan 7 21:22:25] -- SIP/sip_comptel_02-0000000e answered Local/9505755477@default-fb1c,2 [Jan 7 21:22:25] > Channel Local/9505755477@default-fb1c,1 was answered. [Jan 7 21:22:25] ERROR[9982]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe [Jan 7 21:22:25] ERROR[9982]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe [Jan 7 21:22:25] ERROR[9982]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe [Jan 7 21:22:25] == Manager 'sendcron' logged off from 127.0.0.1 [Jan 7 21:22:25] -- Executing [8368@default:1] Playback("Local/9505755477@default-fb1c,1", "sip-silence") in new stack [Jan 7 21:22:25] -- <Local/9505755477@default-fb1c,1> Playing 'sip-silence' (language 'en') [Jan 7 21:22:25] WARNING[10022]: file.c:1292 waitstream_core: Unexpected control subclass '-1' [Jan 7 21:22:25] -- Executing [h@default:1] DeadAGI("Local/9505755477@default-fb1c,2", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----11-----0") in new stack [Jan 7 21:22:25] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16-----ANSWER-----11-----0 completed, returning 0 [Jan 7 21:22:25] == Spawn extension (default, 9505755477, 2) exited non-zero on 'Local/9505755477@default-fb1c,2' [Jan 7 21:22:25] -- Executing [8368@default:2] AGI("SIP/sip_comptel_02-0000000e", "agi://127.0.0.1:4577/call_log") in new stack [Jan 7 21:22:25] -- AGI Script agi://127.0.0.1:4577/call_log completed, returning 0 [Jan 7 21:22:25] -- Executing [8368@default:3] AGI("SIP/sip_comptel_02-0000000e", "agi-VDAD_ALL_outbound.agi|NORMAL-----LB") in new stack [Jan 7 21:22:25] -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDAD_ALL_outbound.agi [Jan 7 21:22:31] -- Playing 'ding' (escape_digits=) (sample_offset 0) [Jan 7 21:22:32] ERROR[10022]: utils.c:966 ast_carefulwrite: write() returned error: Broken pipe [Jan 7 21:22:32] -- AGI Script agi-VDAD_ALL_outbound.agi completed, returning 0 [Jan 7 21:22:32] -- Executing [8307@default:1] Answer("SIP/sip_comptel_02-0000000e", "") in new stack [Jan 7 21:22:32] -- Executing [8307@default:2] Playback("SIP/sip_comptel_02-0000000e", "vm-goodbye") in new stack [Jan 7 21:22:32] -- <SIP/sip_comptel_02-0000000e> Playing 'vm-goodbye' (language 'en') [Jan 7 21:22:33] -- Executing [8307@default:3] Hangup("SIP/sip_comptel_02-0000000e", "") in new stack [Jan 7 21:22:33] == Spawn extension (default, 8307, 3) exited non-zero on 'SIP/sip_comptel_02-0000000e' [Jan 7 21:22:33] -- Executing [h@default:1] DeadAGI("SIP/sip_comptel_02-0000000e", "agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16---------------") in new stack [Jan 7 21:22:33] -- AGI Script agi://127.0.0.1:4577/call_log--HVcauses--PRI-----NODEBUG-----16--------------- completed, returning 0

by **williamconley** » Fri Jan 07, 2011 4:29 pm

cyberahmed wrote:[Jan 7 21:22:25] > Channel Local/9505755477@default-fb1c,1 was answered.

cyberahmed wrote:[Jan 7 21:22:31] -- Playing 'ding' (escape_digits=) (sample_offset 0)

cyberahmed wrote:[Jan 7 21:22:32] -- Executing [8307@default:2] Playback("SIP/sip_comptel_02-0000000e", "vm-goodbye") in new stack

7 seconds after the answer it terminated the call (ie: no agent, time to hang up). i'm just not sure why it was necessary to 'ding' first.

did you have an agent logged in?

perhaps a sip debug would shed some light. or an agi log search (search all the logs in /var/log/astguiclient that have 'agi' in them for that specific time range to see what decisions were made and why)

by **cyberahmed** » Mon Jan 10, 2011 9:02 am

Hi again,

Behind logs for one call from agiout.2011-01-10:

Code: Select all: 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|Perl Environment Dump: 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|0|NORMAL-----LB 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|callerID changed: V0110142629000000233 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|AGI Environment Dump: 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- accountcode = 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- callerid = 0000000000 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- calleridname = V0110142629000000233 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- callingani2 = 0 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- callingpres = 0 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- callingtns = 0 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- callington = 0 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- channel = SIP/sip_comptel_02-00000eb1 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- context = default 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- dnid = unknown 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- enhanced = 0.0 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- extension = 8368 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- language = en 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- priority = 3 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- rdnis = unknown 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- request = agi-VDAD_ALL_outbound.agi 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- type = SIP 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi| -- uniqueid = 1294665990.10007 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|AGI Variables: |1294665990.10007|SIP/sip_comptel_02-00000eb1|8368|SIP|V0110142629000000233|V0110142629000000233|3| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|+++++ VDAD START : |233|2011-01-10 14:26:39|1.4.27.1-vici|3| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|0|SELECT count(*) FROM vicidial_live_agents where callerid='V0110142629000000233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|0|SELECT count(*) FROM vicidial_auto_calls where callerid='V0110142629000000233' and status IN('LIVE','XFER');| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD : |1|update of vac table: V0110142629000000233 |UPDATE vicidial_auto_calls set uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1',status='LIVE',stage='LIVE-0' where callerid='V0110142629000000233' order by call_time desc limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||UPDATE vicidial_list set status='PU' where lead_id='233' and status NOT IN('CALLBK');| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD vicidial_list PU update: |1|1294665990.10007| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||INSERT INTO vicidial_log (uniqueid,lead_id,campaign_id,call_date,start_epoch,status,phone_code,phone_number,user,processed,alt_dial,list_id) values('1294665990.10007','233','ADMIN_TS','2011-01-10 14:26:39','1294665999','QUEUE','1','505755477','VDAD','N','NONE','105')| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD : |233|233|insert to vicidial_log: 1294665990.10007 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and campaign_id = 'ADMIN_TS' and call_time < "2011-01-10 14:26:29" and lead_id != '233';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||SELECT count(*) FROM vicidial_live_agents where campaign_id = 'ADMIN_TS' and last_update_time > '20110110142634';| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi||CONCURRENT TRANSFERS AUTO SETTING: 1 (0)| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|-- VDAD get agent: |0E0|update of vla table: ADMIN_TS|10.10.1.3 |UPDATE vicidial_live_agents set status='QUEUE',lead_id='233',uniqueid='1294665990.10007', channel='SIP/sip_comptel_02-00000eb1', call_server_ip='10.10.1.3', callerid='V0110142629000000233',comments='AUTO' where status = 'READY' and lead_id<1 and campaign_id='ADMIN_TS' and last_update_time > '20110110142634' order by random_id limit 1;| 2011-01-10 14:26:39|agi-VDAD_ALL_outbound.agi|NNNNNNNNNN No agent record found!!! 2011-01-10 14:26:45|agi-VDAD_ALL_outbound.agi||UPDATE vicidial_log FORCE INDEX(lead_id) set status='DROP',end_epoch='1294666005',length_in_sec='5',term_reason='QUEUETIMEOUT' where lead_id = '233' and uniqueid LIKE "1294665990%";| 2011-01-10 14:26:45|agi-VDAD_ALL_outbound.agi|-- VDAD vicidial_log update: |1|1294665990.10007 2011-01-10 14:26:45|agi-VDAD_ALL_outbound.agi||UPDATE vicidial_list set status='DROP' where lead_id = '233';| 2011-01-10 14:26:45|agi-VDAD_ALL_outbound.agi|-- VDAD vicidial_list update: |1|233 2011-01-10 14:26:45|agi-VDAD_ALL_outbound.agi|-- VDAD vac record deleted: |1|ADMIN_TS| 2011-01-10 14:26:45|agi-VDAD_ALL_outbound.agi|exiting the VDAD app, transferring call to 8307

I have logged in system one agent but why system not see him. Agent is logged in and ready.

I'm back to date time issue because in campaign where agents make calls manually date and time on top bar is correct but if campaign set to auto dial (each predictive types) I have see instead of date and time something like NaN-NaN-NaN NaN:NaN:NaN.

by **williamconley** » Mon Jan 10, 2011 10:36 am

i'm drawin a blank. without looking inside your box ...

try vicibox

(sorry, had to say it)

by **gardo** » Mon Jan 10, 2011 4:26 pm

That can be a sign that the communication got interrupted. How are your agents conneting to the GoAutoDial server? Via LAN or WAN? SIP or IAX?

Today I obsorve it another strange symptom because in place where usually is date time on agent interface (top part of) I have something like NaN-NaN-NaN NaN:NaN:NaN. Maybe it is proof on communication problem between server and client.

by **williamconley** » Mon Jan 10, 2011 6:17 pm

Ok, that much is true: generally NaNNANNaNN is the result of a "broken" link between the agent's web browser and the web server portion of the dialer (I've almost never seen it when the agents are in the same room with the dialer unless some ID10T is using Limewire or an equivalent).

by **cyberahmed** » Tue Jan 11, 2011 6:58 am

Hi,

Right! it is problem width communications between agents browser and server but only when campaign is set as autodial (using hooper). Manually calls works fine (without the bigger problems - only from time to time I'm see Dead Calls on black background in real time report)

Server is really strong:
DELL PowerEage 1850
Intel(R) Xeon(TM) CPU 3.00GHz, 4 cores
4 GB Ram
HDD SCSI
2 x 10/100/1000 net interfaces

About my network configuration:

server have connected 2 network interfaces:
first one is connect to LAN (net 10.10.1.0 255.255.255.0) - it using for agents communications width server (all agents are in the local - we have not a remote agents)
second one is connected to public network directly (without NAT) on public IP - it is using to communication with SIP provider.

Once more we using only SIP (agents x-lite, provider sip)

Maybe it is wrong network configuration for goutodial?

by **williamconley** » Tue Jan 11, 2011 10:11 am

1) are you SURE the agents are connecting through the LOCAL network card? (ie: they are using the local ip address, not the server name on both their sip phones AND their web browsers)

2) are you SURE that there is NO other network traffic on the local network besides your vicidial browsers and phones?

3) If this system has never been "fully functional", i'd try a reinstall ... and if that gives the same response, try vicibox install just to see if it reacts differently. When you reinstall DO NOT CHANGE ANYTHING OUTSIDE THE WEB INTERFACE except the IP address (and if you can, set your local network up to make that unnecessary!)

4) And you don't have a firewall (software or hardware) on your local network for this server?

by **cyberahmed** » Fri Mar 18, 2011 5:05 am

Hi William,

For the feedback I change lot of settings of the system and he works very well

. Hooper calling - right transferring conversations to agents without problems, recording calls etc ...

Problem was in the network configuration and the SIP providers which we using, I think.

Just now configuration of our system is very simple. Server is connection to local network via one network interface in the same subnet like agents (without vilan's). To the provider we are connect via one 2xE1 lines (Sangoma A102) and that's all.

In the imminent future we planning remote agents (home worker). I'm testing this and it works very well, but it must be done via vpn (each agents connected to system via vpn's server) - on the public interfaces we have problem with stability sessions. I don't now while but after click hung up button on the agent interfaces system disconnect the current conversation and x-Lite session as well. After then agent must logout and login again, up to next one.

System is very well but everyone must find best configuration for myself

Thanks for yours support and have a nice day

by **williamconley** » Fri Mar 18, 2011 8:57 am

test it with a direct connection (open the ports temporarily for the test) to see if the VPN is the problem. While VPN is cool, if it causes any delays it can kill the system connections. You may have QoS issues or interference.

We have a new system that allows remote agents via IPTables (invisible box to anyone who should not be there, but still allows logins followed by FULL access).

vicidial.org

GoAutoDial - Hooper do not transfer call to agent

GoAutoDial - Hooper do not transfer call to agent

Who is online