Doubts about auto-dial
Posted: Wed Sep 06, 2006 5:30 pm
I´m testing auto-dial.
What I see is that the agent receives the call when the customer phone is still ringing.
I thougt that vicidial would only transfer the call when the customer actually picks-up the phone.
Is that the way vici works or do I hace something wrong?
PD: I´m testing vici with a modem intel X100P compatible, could be an issue with that?
These are the results, you can see that im receiving "[1;30;40m -- [0;37;40mZap/1-1 answered Local/49829467@default-c5d2,2
when this call was never actually answered by customer, it kept ringing and ringing.
[1;30;40m -- [0;37;40mExecuting [1;36;40mAGI[0;37;40m("[1;35;40mLocal/49829467@default-c5d2,2[0;37;40m", "[1;35;40mcall_log.agi|49829467[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
2006-09-06 21:41:17|call_log.agi|Perl Environment Dump:
2006-09-06 21:41:17|call_log.agi|0|49829467
2006-09-06 21:41:17|call_log.agi|AGI Environment Dump:
2006-09-06 21:41:17|call_log.agi| -- accountcode =
2006-09-06 21:41:17|call_log.agi| -- callerid = 0000000000
2006-09-06 21:41:17|call_log.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:17|call_log.agi| -- callingani2 = 0
2006-09-06 21:41:17|call_log.agi| -- callingpres = 0
2006-09-06 21:41:17|call_log.agi| -- callingtns = 0
2006-09-06 21:41:17|call_log.agi| -- callington = 0
2006-09-06 21:41:17|call_log.agi| -- channel = Local/49829467@default-c5d2,2
2006-09-06 21:41:17|call_log.agi| -- context = default
2006-09-06 21:41:17|call_log.agi| -- dnid = unknown
2006-09-06 21:41:17|call_log.agi| -- enhanced = 0.0
2006-09-06 21:41:17|call_log.agi| -- extension = 49829467
2006-09-06 21:41:17|call_log.agi| -- language = en
2006-09-06 21:41:17|call_log.agi| -- priority = 1
2006-09-06 21:41:17|call_log.agi| -- rdnis = unknown
2006-09-06 21:41:17|call_log.agi| -- request = call_log.agi
2006-09-06 21:41:17|call_log.agi| -- type = Local
2006-09-06 21:41:17|call_log.agi| -- uniqueid = 1157593277.34
2006-09-06 21:41:17|call_log.agi|AGI Variables: |1157593277.34|Local/49829467@default-c5d2,2|49829467|Local|V0906214117000000066|
2006-09-06 21:41:17|call_log.agi|+++++ CALL LOG START : 2006-09-06 21:41:17
2006-09-06 21:41:17|call_log.agi||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('1157593277.34','Local/49829467@default-c5d2,2','','Local','127.0.0.1','49829467','','2006-09-06 21:41:17','1157593277','','','','','V0906214117000000066')|
[1;30;40m -- [0;37;40mAGI Script call_log.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mDial[0;37;40m("[1;35;40mLocal/49829467@default-c5d2,2[0;37;40m", "[1;35;40mzap/1/49829467||tTo[0;37;40m") in new stack
[1;30;40m -- [0;37;40mCalled 1/49829467
[1;30;40m == [0;37;40mConnect attempt from '127.0.0.1' unable to authenticate
[1;30;40m == [0;37;40mParsing '/etc/asterisk/manager.conf': Found
Sep 6 21:41:20 [1;33;40mNOTICE[0;37;40m[20926]: [1;37;40mmanager.c[0;37;40m:[1;37;40m575[0;37;40m [1;37;40mauthenticate[0;37;40m: 127.0.0.1 tried to authenticate with nonexistent user 'admin'
[1;30;40m == [0;37;40mConnect attempt from '127.0.0.1' unable to authenticate
[1;30;40m -- [0;37;40mZap/1-1 answered Local/49829467@default-c5d2,2
[1;30;40m > [0;37;40mChannel Local/49829467@default-c5d2,1 was answered.
[1;30;40m -- [0;37;40mExecuting [1;36;40mAGI[0;37;40m("[1;35;40mLocal/49829467@default-c5d2,1[0;37;40m", "[1;35;40mcall_log.agi|8365[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
[1;30;40m == [0;37;40mManager 'sendcron' logged off from 127.0.0.1
[1;30;40m == [0;37;40mSpawn extension (default, 49829467, 2) exited non-zero on 'Local/49829467@default-c5d2,2'
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mLocal/49829467@default-c5d2,2[0;37;40m", "[1;35;40mcall_log.agi|h[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
2006-09-06 21:41:21|call_log.agi|Perl Environment Dump:
2006-09-06 21:41:21|call_log.agi|0|8365
2006-09-06 21:41:21|call_log.agi|AGI Environment Dump:
2006-09-06 21:41:21|call_log.agi| -- accountcode =
2006-09-06 21:41:21|call_log.agi| -- callerid = 0000000000
2006-09-06 21:41:21|call_log.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:21|call_log.agi| -- callingani2 = 0
2006-09-06 21:41:21|call_log.agi| -- callingpres = 0
2006-09-06 21:41:21|call_log.agi| -- callingtns = 0
2006-09-06 21:41:21|call_log.agi| -- callington = 0
2006-09-06 21:41:21|call_log.agi| -- channel = Local/49829467@default-c5d2,1
2006-09-06 21:41:21|call_log.agi| -- context = default
2006-09-06 21:41:21|call_log.agi| -- dnid = unknown
2006-09-06 21:41:21|call_log.agi| -- enhanced = 0.0
2006-09-06 21:41:21|call_log.agi| -- extension = 8365
2006-09-06 21:41:21|call_log.agi| -- language = en
2006-09-06 21:41:21|call_log.agi| -- priority = 1
2006-09-06 21:41:21|call_log.agi| -- rdnis = unknown
2006-09-06 21:41:21|call_log.agi| -- request = call_log.agi
2006-09-06 21:41:21|call_log.agi| -- type = Local
2006-09-06 21:41:21|call_log.agi| -- uniqueid = 1157593277.33
2006-09-06 21:41:21|call_log.agi|AGI Variables: |1157593277.33|Local/49829467@default-c5d2,1|8365|Local|V0906214117000000066|
2006-09-06 21:41:21|call_log.agi|+++++ CALL LOG START : 2006-09-06 21:41:21
2006-09-06 21:41:21|call_log.agi||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('1157593277.33','Local/49829467@default-c5d2,1','','Local','127.0.0.1','8365','','2006-09-06 21:41:21','1157593281','','','','','V0906214117000000066')|
[1;30;40m -- [0;37;40mAGI Script call_log.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mAGI[0;37;40m("[1;35;40mZap/1-1[0;37;40m", "[1;35;40magi-VDADtransfer.agi|8365[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
2006-09-06 21:41:21|call_log.agi|AGI Environment Dump:
2006-09-06 21:41:21|call_log.agi| -- accountcode =
2006-09-06 21:41:21|call_log.agi| -- callerid = 0000000000
2006-09-06 21:41:21|call_log.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:21|call_log.agi| -- callingani2 = 0
2006-09-06 21:41:21|call_log.agi| -- callingpres = 0
2006-09-06 21:41:21|call_log.agi| -- callingtns = 0
2006-09-06 21:41:21|call_log.agi| -- callington = 0
2006-09-06 21:41:21|call_log.agi| -- channel = Local/49829467@default-c5d2,2
2006-09-06 21:41:21|call_log.agi| -- context = default
2006-09-06 21:41:21|call_log.agi| -- dnid = unknown
2006-09-06 21:41:21|call_log.agi| -- enhanced = 0.0
2006-09-06 21:41:21|call_log.agi| -- extension = h
2006-09-06 21:41:21|call_log.agi| -- language = en
2006-09-06 21:41:21|call_log.agi| -- priority = 1
2006-09-06 21:41:21|call_log.agi| -- rdnis = unknown
2006-09-06 21:41:21|call_log.agi| -- request = call_log.agi
2006-09-06 21:41:21|call_log.agi| -- type = Local
2006-09-06 21:41:21|call_log.agi| -- uniqueid = 1157593277.34
2006-09-06 21:41:21|call_log.agi|AGI Variables: |1157593277.34|Local/49829467@default-c5d2,2|h|Local|V0906214117000000066|
2006-09-06 21:41:21|call_log.agi||CALL HUNG UP|
2006-09-06 21:41:21|call_log.agi||1157593277.3399999|1157593277|
2006-09-06 21:41:21|call_log.agi|QUERY done: start time = 1157593277 | sec: 4 | min: 0.07 |
2006-09-06 21:41:21|call_log.agi||UPDATE call_log set end_time='2006-09-06 21:41:21',end_epoch='1157593281',length_in_sec=4,length_in_min=' 0.07' where uniqueid='1157593277.34'|
2006-09-06 21:41:21|call_log.agi||DELETE from live_inbound where uniqueid='1157593277.34' and server_ip='127.0.0.1'|
2006-09-06 21:41:21|call_log.agi|+++++ CALL LOG HUNGUP: |1157593277.34|Local/49829467@default-c5d2,2|h|2006-09-06 21:41:21|min: 0.07|
[1;30;40m -- [0;37;40mAGI Script call_log.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mLocal/49829467@default-c5d2,2[0;37;40m", "[1;35;40mVD_hangup.agi|PRI-----NODEBUG-----16-----ANSWER-----4-----0[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
2006-09-06 21:41:22|agi-VDADtransfer.agi|Perl Environment Dump:
2006-09-06 21:41:22|agi-VDADtransfer.agi|0|8365
2006-09-06 21:41:22|agi-VDADtransfer.agi|callerID changed: V0906214117000000066
2006-09-06 21:41:22|agi-VDADtransfer.agi|AGI Environment Dump:
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- accountcode =
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- callerid = 0000000000
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- callingani2 = 0
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- callingpres = 0
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- callingtns = 0
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- callington = 0
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- channel = Zap/1-1
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- context = default
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- dnid = unknown
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- enhanced = 0.0
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- extension = 8365
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- language = en
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- priority = 2
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- rdnis = unknown
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- request = agi-VDADtransfer.agi
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- type = Zap
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- uniqueid = 1157593277.33
2006-09-06 21:41:22|agi-VDADtransfer.agi|AGI Variables: |1157593277.33|Zap/1-1|8365|Zap|V0906214117000000066|V0906214117000000066|2|
2006-09-06 21:41:22|agi-VDADtransfer.agi|+++++ VDAD START : |66|2006-09-06 21:41:22|1.2|2|
2006-09-06 21:41:22|agi-VDADtransfer.agi|-- VDAD : |1|update of vac table: V0906214117000000066
|UPDATE vicidial_auto_calls set uniqueid='1157593277.33', channel='Zap/1-1',status='LIVE' where callerid='V0906214117000000066' order by call_time desc limit 1;|
2006-09-06 21:41:22|agi-VDADtransfer.agi||INSERT INTO vicidial_log (uniqueid,lead_id,campaign_id,call_date,start_epoch,status,phone_code,phone_number,user,processed) values('1157593277.33','66','SAMUEL','2006-09-06 21:41:22','1157593282','QUEUE','1','49829467','VDAD','N')|
2006-09-06 21:41:22|agi-VDADtransfer.agi|-- VDAD : |66|66|insert to vicidial_log: 1157593277.33
2006-09-06 21:41:22|agi-VDADtransfer.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='127.0.0.1' and campaign_id = 'SAMUEL' and call_time < "2006-09-06 21:41:17" and lead_id != '66';|
2006-09-06 21:41:22|agi-VDADtransfer.agi|-- VDAD get agent: |1|update of vla table: SAMUEL|127.0.0.1
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='66',uniqueid='1157593277.33', channel='Zap/1-1', callerid='V0906214117000000066' where status = 'READY' and server_ip='127.0.0.1' and campaign_id='SAMUEL' and last_update_time > '19691231185955' order by last_call_time limit 1;|
2006-09-06 21:41:22|agi-VDADtransfer.agi||SELECT conf_exten,user,extension FROM vicidial_live_agents where status = 'QUEUE' and server_ip='127.0.0.1' and campaign_id='SAMUEL' and callerid='V0906214117000000066' and channel='Zap/1-1' order by last_call_time limit 1;|
2006-09-06 21:41:22|agi-VDADtransfer.agi|-- VDAD XFER : |1|update of vac table: V0906214117000000066
|UPDATE vicidial_auto_calls set status='XFER' where callerid='V0906214117000000066';|
2006-09-06 21:41:22|agi-VDADtransfer.agi|exiting the VDAD app, transferring call to 8600051
2006-09-06 21:41:22|agi-VDADtransfer.agi|XXXXXXXXXX VDAD transferred: start|stop 2006-09-06 21:41:22|2006-09-06 21:41:22
[1;30;40m -- [0;37;40mAGI Script agi-VDADtransfer.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mMeetMe[0;37;40m("[1;35;40mZap/1-1[0;37;40m", "[1;35;40m8600051[0;37;40m") in new stack
2006-09-06 21:41:22|VD_hangup.agi|Perl Environment Dump:
2006-09-06 21:41:22|VD_hangup.agi|0|PRI-----NODEBUG-----16-----ANSWER-----4-----0
2006-09-06 21:41:22|VD_hangup.agi|AGI Environment Dump:
2006-09-06 21:41:22|VD_hangup.agi| -- accountcode =
2006-09-06 21:41:22|VD_hangup.agi| -- callerid = 0000000000
2006-09-06 21:41:22|VD_hangup.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:22|VD_hangup.agi| -- callingani2 = 0
2006-09-06 21:41:22|VD_hangup.agi| -- callingpres = 0
2006-09-06 21:41:22|VD_hangup.agi| -- callingtns = 0
2006-09-06 21:41:22|VD_hangup.agi| -- callington = 0
2006-09-06 21:41:22|VD_hangup.agi| -- channel = Local/49829467@default-c5d2,2
2006-09-06 21:41:22|VD_hangup.agi| -- context = default
2006-09-06 21:41:22|VD_hangup.agi| -- dnid = unknown
2006-09-06 21:41:22|VD_hangup.agi| -- enhanced = 0.0
2006-09-06 21:41:22|VD_hangup.agi| -- extension = h
2006-09-06 21:41:22|VD_hangup.agi| -- language = en
2006-09-06 21:41:22|VD_hangup.agi| -- priority = 2
2006-09-06 21:41:22|VD_hangup.agi| -- rdnis = unknown
2006-09-06 21:41:22|VD_hangup.agi| -- request = VD_hangup.agi
2006-09-06 21:41:22|VD_hangup.agi| -- type = Local
2006-09-06 21:41:22|VD_hangup.agi| -- uniqueid = 1157593277.34
2006-09-06 21:41:22|VD_hangup.agi|AGI Variables: |1157593277.34|Local/49829467@default-c5d2,2|h|Local|V0906214117000000066|
2006-09-06 21:41:22|VD_hangup.agi|DEBUG: NODEBUG
2006-09-06 21:41:22|VD_hangup.agi|VD_hangup : V0906214117000000066 Local/49829467@default-c5d2,2 2 66
2006-09-06 21:41:22|VD_hangup.agi|-- VDhangup Local DEBUG: |PRI|V0906214117000000066|ANSWER|16|
2006-09-06 21:41:22|VD_hangup.agi|+++++ VDAD START LOCAL CHANNEL: EXITING- 2
[1;30;40m -- [0;37;40mAGI Script VD_hangup.agi completed, returning 0
[1;30;40m == [0;37;40mParsing '/etc/asterisk/manager.conf': Found
Sep 6 21:41:22 [1;33;40mNOTICE[0;37;40m[20957]: [1;37;40mmanager.c[0;37;40m:[1;37;40m575[0;37;40m [1;37;40mauthenticate[0;37;40m: 127.0.0.1 tried to authenticate with nonexistent user 'remote_mgr'
[1;30;40m == [0;37;40mConnect attempt from '127.0.0.1' unable to authenticate
Beginning asterisk shutdown....
[1;30;40m == [0;37;40mSpawn extension (default, 8600051, 1) exited non-zero on 'IAX2/1000-33'
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mIAX2/1000-33[0;37;40m", "[1;35;40mcall_log.agi|h[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
2006-09-06 21:41:27|call_log.agi|AGI Environment Dump:
2006-09-06 21:41:27|call_log.agi| -- accountcode =
2006-09-06 21:41:27|call_log.agi| -- callerid = unknown
2006-09-06 21:41:27|call_log.agi| -- calleridname = S0609062141048600051
2006-09-06 21:41:27|call_log.agi| -- callingani2 = 0
2006-09-06 21:41:27|call_log.agi| -- callingpres = 0
2006-09-06 21:41:27|call_log.agi| -- callingtns = 0
2006-09-06 21:41:27|call_log.agi| -- callington = 0
2006-09-06 21:41:27|call_log.agi| -- channel = IAX2/1000-33
2006-09-06 21:41:27|call_log.agi| -- context = default
2006-09-06 21:41:27|call_log.agi| -- dnid = unknown
2006-09-06 21:41:27|call_log.agi| -- enhanced = 0.0
2006-09-06 21:41:27|call_log.agi| -- extension = h
2006-09-06 21:41:27|call_log.agi| -- language = en
2006-09-06 21:41:27|call_log.agi| -- priority = 1
2006-09-06 21:41:27|call_log.agi| -- rdnis = unknown
2006-09-06 21:41:27|call_log.agi| -- request = call_log.agi
2006-09-06 21:41:27|call_log.agi| -- type = IAX2
2006-09-06 21:41:27|call_log.agi| -- uniqueid = 1157593264.31
2006-09-06 21:41:27|call_log.agi|AGI Variables: |1157593264.31|IAX2/1000-33|h|IAX2|S0609062141048600051|
2006-09-06 21:41:27|call_log.agi||CALL HUNG UP|
2006-09-06 21:41:27|call_log.agi||DELETE from live_inbound where uniqueid='1157593264.31' and server_ip='127.0.0.1'|
2006-09-06 21:41:27|call_log.agi|+++++ CALL LOG HUNGUP: |1157593264.31|IAX2/1000-33|h|2006-09-06 21:41:27|min: |
[1;30;40m -- [0;37;40mAGI Script call_log.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mIAX2/1000-33[0;37;40m", "[1;35;40mVD_hangup.agi|PRI-----NODEBUG-----0---------------[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
2006-09-06 21:41:27|VD_hangup.agi|Perl Environment Dump:
2006-09-06 21:41:27|VD_hangup.agi|0|PRI-----NODEBUG-----0---------------
2006-09-06 21:41:27|VD_hangup.agi|AGI Environment Dump:
2006-09-06 21:41:27|VD_hangup.agi| -- accountcode =
2006-09-06 21:41:27|VD_hangup.agi| -- callerid = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- calleridname = S0609062141048600051
2006-09-06 21:41:27|VD_hangup.agi| -- callingani2 = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callingpres = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callingtns = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callington = 0
2006-09-06 21:41:27|VD_hangup.agi| -- channel = IAX2/1000-33
2006-09-06 21:41:27|VD_hangup.agi| -- context = default
2006-09-06 21:41:27|VD_hangup.agi| -- dnid = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- enhanced = 0.0
2006-09-06 21:41:27|VD_hangup.agi| -- extension = h
2006-09-06 21:41:27|VD_hangup.agi| -- language = en
2006-09-06 21:41:27|VD_hangup.agi| -- priority = 2
2006-09-06 21:41:27|VD_hangup.agi| -- rdnis = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- request = VD_hangup.agi
2006-09-06 21:41:27|VD_hangup.agi| -- type = IAX2
2006-09-06 21:41:27|VD_hangup.agi| -- uniqueid = 1157593264.31
2006-09-06 21:41:27|VD_hangup.agi|AGI Variables: |1157593264.31|IAX2/1000-33|h|IAX2|S0609062141048600051|
2006-09-06 21:41:27|VD_hangup.agi|DEBUG: NODEBUG
2006-09-06 21:41:27|VD_hangup.agi|VD_hangup : S0609062141048600051 IAX2/1000-33 2 48600051
2006-09-06 21:41:27|VD_hangup.agi||SELECT lead_id,callerid FROM vicidial_auto_calls where uniqueid = '1157593264.31' limit 1;|
2006-09-06 21:41:27|VD_hangup.agi|VD hangup: no VDAC record found: 1157593264.31 S0609062141048600051
[1;30;40m -- [0;37;40mAGI Script VD_hangup.agi completed, returning 0
[1;30;40m -- [0;37;40mHungup 'IAX2/1000-33'
[1;30;40m -- [0;37;40mHungup 'Zap/pseudo-728086434'
[1;30;40m == [0;37;40mSpawn extension (default, 8600051, 1) exited non-zero on 'Zap/1-1'
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mZap/1-1[0;37;40m", "[1;35;40mcall_log.agi|h[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
2006-09-06 21:41:27|call_log.agi|AGI Environment Dump:
2006-09-06 21:41:27|call_log.agi| -- accountcode =
2006-09-06 21:41:27|call_log.agi| -- callerid = unknown
2006-09-06 21:41:27|call_log.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:27|call_log.agi| -- callingani2 = 0
2006-09-06 21:41:27|call_log.agi| -- callingpres = 0
2006-09-06 21:41:27|call_log.agi| -- callingtns = 0
2006-09-06 21:41:27|call_log.agi| -- callington = 0
2006-09-06 21:41:27|call_log.agi| -- channel = Zap/1-1
2006-09-06 21:41:27|call_log.agi| -- context = default
2006-09-06 21:41:27|call_log.agi| -- dnid = unknown
2006-09-06 21:41:27|call_log.agi| -- enhanced = 0.0
2006-09-06 21:41:27|call_log.agi| -- extension = h
2006-09-06 21:41:27|call_log.agi| -- language = en
2006-09-06 21:41:27|call_log.agi| -- priority = 1
2006-09-06 21:41:27|call_log.agi| -- rdnis = unknown
2006-09-06 21:41:27|call_log.agi| -- request = call_log.agi
2006-09-06 21:41:27|call_log.agi| -- type = Zap
2006-09-06 21:41:27|call_log.agi| -- uniqueid = 1157593277.33
2006-09-06 21:41:27|call_log.agi|AGI Variables: |1157593277.33|Zap/1-1|h|Zap|V0906214117000000066|
2006-09-06 21:41:27|call_log.agi||CALL HUNG UP|
2006-09-06 21:41:27|call_log.agi||1157593277.3299999|1157593281|
2006-09-06 21:41:27|call_log.agi|QUERY done: start time = 1157593281 | sec: 6 | min: 0.10 |
2006-09-06 21:41:27|call_log.agi||UPDATE call_log set end_time='2006-09-06 21:41:27',end_epoch='1157593287',length_in_sec=6,length_in_min=' 0.10' where uniqueid='1157593277.33'|
2006-09-06 21:41:27|call_log.agi||DELETE from live_inbound where uniqueid='1157593277.33' and server_ip='127.0.0.1'|
2006-09-06 21:41:27|call_log.agi|+++++ CALL LOG HUNGUP: |1157593277.33|Zap/1-1|h|2006-09-06 21:41:27|min: 0.10|
[1;30;40m -- [0;37;40mAGI Script call_log.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mZap/1-1[0;37;40m", "[1;35;40mVD_hangup.agi|PRI-----NODEBUG-----0---------------[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
2006-09-06 21:41:27|VD_hangup.agi|Perl Environment Dump:
2006-09-06 21:41:27|VD_hangup.agi|0|PRI-----NODEBUG-----0---------------
2006-09-06 21:41:27|VD_hangup.agi|AGI Environment Dump:
2006-09-06 21:41:27|VD_hangup.agi| -- accountcode =
2006-09-06 21:41:27|VD_hangup.agi| -- callerid = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:27|VD_hangup.agi| -- callingani2 = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callingpres = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callingtns = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callington = 0
2006-09-06 21:41:27|VD_hangup.agi| -- channel = Zap/1-1
2006-09-06 21:41:27|VD_hangup.agi| -- context = default
2006-09-06 21:41:27|VD_hangup.agi| -- dnid = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- enhanced = 0.0
2006-09-06 21:41:27|VD_hangup.agi| -- extension = h
2006-09-06 21:41:27|VD_hangup.agi| -- language = en
2006-09-06 21:41:27|VD_hangup.agi| -- priority = 2
2006-09-06 21:41:27|VD_hangup.agi| -- rdnis = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- request = VD_hangup.agi
2006-09-06 21:41:27|VD_hangup.agi| -- type = Zap
2006-09-06 21:41:27|VD_hangup.agi| -- uniqueid = 1157593277.33
2006-09-06 21:41:27|VD_hangup.agi|AGI Variables: |1157593277.33|Zap/1-1|h|Zap|V0906214117000000066|
2006-09-06 21:41:27|VD_hangup.agi|DEBUG: NODEBUG
2006-09-06 21:41:27|VD_hangup.agi|VD_hangup : V0906214117000000066 Zap/1-1 2 66
2006-09-06 21:41:27|VD_hangup.agi||SELECT lead_id,callerid FROM vicidial_auto_calls where uniqueid = '1157593277.33' limit 1;|
2006-09-06 21:41:27|VD_hangup.agi|-- VDAC record deleted: |1| |66|1157593277.33|V0906214117000000066|127.0.0.1
2006-09-06 21:41:27|VD_hangup.agi||SELECT start_epoch,status FROM vicidial_log where uniqueid='1157593277.33' and lead_id='66' limit 1;|
2006-09-06 21:41:27|VD_hangup.agi|no VDL record found: 1157593277.33 V0906214117000000066 66 1157593277.33
2006-09-06 21:41:27|VD_hangup.agi||SELECT start_epoch,status,closecallid FROM vicidial_closer_log where lead_id = '66' and call_date > "2006-09-06 15:41:27" order by call_date desc limit 1;|
2006-09-06 21:41:27|VD_hangup.agi|no VDL or VDCL record found: 1157593277.33 V0906214117000000066 66 1157593277.33
[1;30;40m -- [0;37;40mAGI Script VD_hangup.agi completed, returning 0
[1;30;40m -- [0;37;40mHungup 'Zap/1-1'
Executing last minute cleanups
[1;30;40m == [0;37;40mDestroying musiconhold processes
Asterisk cleanly ending (2).
[0m]0;root@asterisk1:/var/log/astguiclient[root@asterisk1 astguiclient]#
What I see is that the agent receives the call when the customer phone is still ringing.
I thougt that vicidial would only transfer the call when the customer actually picks-up the phone.
Is that the way vici works or do I hace something wrong?
PD: I´m testing vici with a modem intel X100P compatible, could be an issue with that?
These are the results, you can see that im receiving "[1;30;40m -- [0;37;40mZap/1-1 answered Local/49829467@default-c5d2,2
when this call was never actually answered by customer, it kept ringing and ringing.
[1;30;40m -- [0;37;40mExecuting [1;36;40mAGI[0;37;40m("[1;35;40mLocal/49829467@default-c5d2,2[0;37;40m", "[1;35;40mcall_log.agi|49829467[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
2006-09-06 21:41:17|call_log.agi|Perl Environment Dump:
2006-09-06 21:41:17|call_log.agi|0|49829467
2006-09-06 21:41:17|call_log.agi|AGI Environment Dump:
2006-09-06 21:41:17|call_log.agi| -- accountcode =
2006-09-06 21:41:17|call_log.agi| -- callerid = 0000000000
2006-09-06 21:41:17|call_log.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:17|call_log.agi| -- callingani2 = 0
2006-09-06 21:41:17|call_log.agi| -- callingpres = 0
2006-09-06 21:41:17|call_log.agi| -- callingtns = 0
2006-09-06 21:41:17|call_log.agi| -- callington = 0
2006-09-06 21:41:17|call_log.agi| -- channel = Local/49829467@default-c5d2,2
2006-09-06 21:41:17|call_log.agi| -- context = default
2006-09-06 21:41:17|call_log.agi| -- dnid = unknown
2006-09-06 21:41:17|call_log.agi| -- enhanced = 0.0
2006-09-06 21:41:17|call_log.agi| -- extension = 49829467
2006-09-06 21:41:17|call_log.agi| -- language = en
2006-09-06 21:41:17|call_log.agi| -- priority = 1
2006-09-06 21:41:17|call_log.agi| -- rdnis = unknown
2006-09-06 21:41:17|call_log.agi| -- request = call_log.agi
2006-09-06 21:41:17|call_log.agi| -- type = Local
2006-09-06 21:41:17|call_log.agi| -- uniqueid = 1157593277.34
2006-09-06 21:41:17|call_log.agi|AGI Variables: |1157593277.34|Local/49829467@default-c5d2,2|49829467|Local|V0906214117000000066|
2006-09-06 21:41:17|call_log.agi|+++++ CALL LOG START : 2006-09-06 21:41:17
2006-09-06 21:41:17|call_log.agi||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('1157593277.34','Local/49829467@default-c5d2,2','','Local','127.0.0.1','49829467','','2006-09-06 21:41:17','1157593277','','','','','V0906214117000000066')|
[1;30;40m -- [0;37;40mAGI Script call_log.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mDial[0;37;40m("[1;35;40mLocal/49829467@default-c5d2,2[0;37;40m", "[1;35;40mzap/1/49829467||tTo[0;37;40m") in new stack
[1;30;40m -- [0;37;40mCalled 1/49829467
[1;30;40m == [0;37;40mConnect attempt from '127.0.0.1' unable to authenticate
[1;30;40m == [0;37;40mParsing '/etc/asterisk/manager.conf': Found
Sep 6 21:41:20 [1;33;40mNOTICE[0;37;40m[20926]: [1;37;40mmanager.c[0;37;40m:[1;37;40m575[0;37;40m [1;37;40mauthenticate[0;37;40m: 127.0.0.1 tried to authenticate with nonexistent user 'admin'
[1;30;40m == [0;37;40mConnect attempt from '127.0.0.1' unable to authenticate
[1;30;40m -- [0;37;40mZap/1-1 answered Local/49829467@default-c5d2,2
[1;30;40m > [0;37;40mChannel Local/49829467@default-c5d2,1 was answered.
[1;30;40m -- [0;37;40mExecuting [1;36;40mAGI[0;37;40m("[1;35;40mLocal/49829467@default-c5d2,1[0;37;40m", "[1;35;40mcall_log.agi|8365[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
[1;30;40m == [0;37;40mManager 'sendcron' logged off from 127.0.0.1
[1;30;40m == [0;37;40mSpawn extension (default, 49829467, 2) exited non-zero on 'Local/49829467@default-c5d2,2'
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mLocal/49829467@default-c5d2,2[0;37;40m", "[1;35;40mcall_log.agi|h[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
2006-09-06 21:41:21|call_log.agi|Perl Environment Dump:
2006-09-06 21:41:21|call_log.agi|0|8365
2006-09-06 21:41:21|call_log.agi|AGI Environment Dump:
2006-09-06 21:41:21|call_log.agi| -- accountcode =
2006-09-06 21:41:21|call_log.agi| -- callerid = 0000000000
2006-09-06 21:41:21|call_log.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:21|call_log.agi| -- callingani2 = 0
2006-09-06 21:41:21|call_log.agi| -- callingpres = 0
2006-09-06 21:41:21|call_log.agi| -- callingtns = 0
2006-09-06 21:41:21|call_log.agi| -- callington = 0
2006-09-06 21:41:21|call_log.agi| -- channel = Local/49829467@default-c5d2,1
2006-09-06 21:41:21|call_log.agi| -- context = default
2006-09-06 21:41:21|call_log.agi| -- dnid = unknown
2006-09-06 21:41:21|call_log.agi| -- enhanced = 0.0
2006-09-06 21:41:21|call_log.agi| -- extension = 8365
2006-09-06 21:41:21|call_log.agi| -- language = en
2006-09-06 21:41:21|call_log.agi| -- priority = 1
2006-09-06 21:41:21|call_log.agi| -- rdnis = unknown
2006-09-06 21:41:21|call_log.agi| -- request = call_log.agi
2006-09-06 21:41:21|call_log.agi| -- type = Local
2006-09-06 21:41:21|call_log.agi| -- uniqueid = 1157593277.33
2006-09-06 21:41:21|call_log.agi|AGI Variables: |1157593277.33|Local/49829467@default-c5d2,1|8365|Local|V0906214117000000066|
2006-09-06 21:41:21|call_log.agi|+++++ CALL LOG START : 2006-09-06 21:41:21
2006-09-06 21:41:21|call_log.agi||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('1157593277.33','Local/49829467@default-c5d2,1','','Local','127.0.0.1','8365','','2006-09-06 21:41:21','1157593281','','','','','V0906214117000000066')|
[1;30;40m -- [0;37;40mAGI Script call_log.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mAGI[0;37;40m("[1;35;40mZap/1-1[0;37;40m", "[1;35;40magi-VDADtransfer.agi|8365[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
2006-09-06 21:41:21|call_log.agi|AGI Environment Dump:
2006-09-06 21:41:21|call_log.agi| -- accountcode =
2006-09-06 21:41:21|call_log.agi| -- callerid = 0000000000
2006-09-06 21:41:21|call_log.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:21|call_log.agi| -- callingani2 = 0
2006-09-06 21:41:21|call_log.agi| -- callingpres = 0
2006-09-06 21:41:21|call_log.agi| -- callingtns = 0
2006-09-06 21:41:21|call_log.agi| -- callington = 0
2006-09-06 21:41:21|call_log.agi| -- channel = Local/49829467@default-c5d2,2
2006-09-06 21:41:21|call_log.agi| -- context = default
2006-09-06 21:41:21|call_log.agi| -- dnid = unknown
2006-09-06 21:41:21|call_log.agi| -- enhanced = 0.0
2006-09-06 21:41:21|call_log.agi| -- extension = h
2006-09-06 21:41:21|call_log.agi| -- language = en
2006-09-06 21:41:21|call_log.agi| -- priority = 1
2006-09-06 21:41:21|call_log.agi| -- rdnis = unknown
2006-09-06 21:41:21|call_log.agi| -- request = call_log.agi
2006-09-06 21:41:21|call_log.agi| -- type = Local
2006-09-06 21:41:21|call_log.agi| -- uniqueid = 1157593277.34
2006-09-06 21:41:21|call_log.agi|AGI Variables: |1157593277.34|Local/49829467@default-c5d2,2|h|Local|V0906214117000000066|
2006-09-06 21:41:21|call_log.agi||CALL HUNG UP|
2006-09-06 21:41:21|call_log.agi||1157593277.3399999|1157593277|
2006-09-06 21:41:21|call_log.agi|QUERY done: start time = 1157593277 | sec: 4 | min: 0.07 |
2006-09-06 21:41:21|call_log.agi||UPDATE call_log set end_time='2006-09-06 21:41:21',end_epoch='1157593281',length_in_sec=4,length_in_min=' 0.07' where uniqueid='1157593277.34'|
2006-09-06 21:41:21|call_log.agi||DELETE from live_inbound where uniqueid='1157593277.34' and server_ip='127.0.0.1'|
2006-09-06 21:41:21|call_log.agi|+++++ CALL LOG HUNGUP: |1157593277.34|Local/49829467@default-c5d2,2|h|2006-09-06 21:41:21|min: 0.07|
[1;30;40m -- [0;37;40mAGI Script call_log.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mLocal/49829467@default-c5d2,2[0;37;40m", "[1;35;40mVD_hangup.agi|PRI-----NODEBUG-----16-----ANSWER-----4-----0[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
2006-09-06 21:41:22|agi-VDADtransfer.agi|Perl Environment Dump:
2006-09-06 21:41:22|agi-VDADtransfer.agi|0|8365
2006-09-06 21:41:22|agi-VDADtransfer.agi|callerID changed: V0906214117000000066
2006-09-06 21:41:22|agi-VDADtransfer.agi|AGI Environment Dump:
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- accountcode =
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- callerid = 0000000000
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- callingani2 = 0
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- callingpres = 0
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- callingtns = 0
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- callington = 0
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- channel = Zap/1-1
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- context = default
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- dnid = unknown
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- enhanced = 0.0
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- extension = 8365
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- language = en
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- priority = 2
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- rdnis = unknown
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- request = agi-VDADtransfer.agi
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- type = Zap
2006-09-06 21:41:22|agi-VDADtransfer.agi| -- uniqueid = 1157593277.33
2006-09-06 21:41:22|agi-VDADtransfer.agi|AGI Variables: |1157593277.33|Zap/1-1|8365|Zap|V0906214117000000066|V0906214117000000066|2|
2006-09-06 21:41:22|agi-VDADtransfer.agi|+++++ VDAD START : |66|2006-09-06 21:41:22|1.2|2|
2006-09-06 21:41:22|agi-VDADtransfer.agi|-- VDAD : |1|update of vac table: V0906214117000000066
|UPDATE vicidial_auto_calls set uniqueid='1157593277.33', channel='Zap/1-1',status='LIVE' where callerid='V0906214117000000066' order by call_time desc limit 1;|
2006-09-06 21:41:22|agi-VDADtransfer.agi||INSERT INTO vicidial_log (uniqueid,lead_id,campaign_id,call_date,start_epoch,status,phone_code,phone_number,user,processed) values('1157593277.33','66','SAMUEL','2006-09-06 21:41:22','1157593282','QUEUE','1','49829467','VDAD','N')|
2006-09-06 21:41:22|agi-VDADtransfer.agi|-- VDAD : |66|66|insert to vicidial_log: 1157593277.33
2006-09-06 21:41:22|agi-VDADtransfer.agi||SELECT count(*) FROM vicidial_auto_calls where status = 'LIVE' and server_ip='127.0.0.1' and campaign_id = 'SAMUEL' and call_time < "2006-09-06 21:41:17" and lead_id != '66';|
2006-09-06 21:41:22|agi-VDADtransfer.agi|-- VDAD get agent: |1|update of vla table: SAMUEL|127.0.0.1
|UPDATE vicidial_live_agents set status='QUEUE',lead_id='66',uniqueid='1157593277.33', channel='Zap/1-1', callerid='V0906214117000000066' where status = 'READY' and server_ip='127.0.0.1' and campaign_id='SAMUEL' and last_update_time > '19691231185955' order by last_call_time limit 1;|
2006-09-06 21:41:22|agi-VDADtransfer.agi||SELECT conf_exten,user,extension FROM vicidial_live_agents where status = 'QUEUE' and server_ip='127.0.0.1' and campaign_id='SAMUEL' and callerid='V0906214117000000066' and channel='Zap/1-1' order by last_call_time limit 1;|
2006-09-06 21:41:22|agi-VDADtransfer.agi|-- VDAD XFER : |1|update of vac table: V0906214117000000066
|UPDATE vicidial_auto_calls set status='XFER' where callerid='V0906214117000000066';|
2006-09-06 21:41:22|agi-VDADtransfer.agi|exiting the VDAD app, transferring call to 8600051
2006-09-06 21:41:22|agi-VDADtransfer.agi|XXXXXXXXXX VDAD transferred: start|stop 2006-09-06 21:41:22|2006-09-06 21:41:22
[1;30;40m -- [0;37;40mAGI Script agi-VDADtransfer.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mMeetMe[0;37;40m("[1;35;40mZap/1-1[0;37;40m", "[1;35;40m8600051[0;37;40m") in new stack
2006-09-06 21:41:22|VD_hangup.agi|Perl Environment Dump:
2006-09-06 21:41:22|VD_hangup.agi|0|PRI-----NODEBUG-----16-----ANSWER-----4-----0
2006-09-06 21:41:22|VD_hangup.agi|AGI Environment Dump:
2006-09-06 21:41:22|VD_hangup.agi| -- accountcode =
2006-09-06 21:41:22|VD_hangup.agi| -- callerid = 0000000000
2006-09-06 21:41:22|VD_hangup.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:22|VD_hangup.agi| -- callingani2 = 0
2006-09-06 21:41:22|VD_hangup.agi| -- callingpres = 0
2006-09-06 21:41:22|VD_hangup.agi| -- callingtns = 0
2006-09-06 21:41:22|VD_hangup.agi| -- callington = 0
2006-09-06 21:41:22|VD_hangup.agi| -- channel = Local/49829467@default-c5d2,2
2006-09-06 21:41:22|VD_hangup.agi| -- context = default
2006-09-06 21:41:22|VD_hangup.agi| -- dnid = unknown
2006-09-06 21:41:22|VD_hangup.agi| -- enhanced = 0.0
2006-09-06 21:41:22|VD_hangup.agi| -- extension = h
2006-09-06 21:41:22|VD_hangup.agi| -- language = en
2006-09-06 21:41:22|VD_hangup.agi| -- priority = 2
2006-09-06 21:41:22|VD_hangup.agi| -- rdnis = unknown
2006-09-06 21:41:22|VD_hangup.agi| -- request = VD_hangup.agi
2006-09-06 21:41:22|VD_hangup.agi| -- type = Local
2006-09-06 21:41:22|VD_hangup.agi| -- uniqueid = 1157593277.34
2006-09-06 21:41:22|VD_hangup.agi|AGI Variables: |1157593277.34|Local/49829467@default-c5d2,2|h|Local|V0906214117000000066|
2006-09-06 21:41:22|VD_hangup.agi|DEBUG: NODEBUG
2006-09-06 21:41:22|VD_hangup.agi|VD_hangup : V0906214117000000066 Local/49829467@default-c5d2,2 2 66
2006-09-06 21:41:22|VD_hangup.agi|-- VDhangup Local DEBUG: |PRI|V0906214117000000066|ANSWER|16|
2006-09-06 21:41:22|VD_hangup.agi|+++++ VDAD START LOCAL CHANNEL: EXITING- 2
[1;30;40m -- [0;37;40mAGI Script VD_hangup.agi completed, returning 0
[1;30;40m == [0;37;40mParsing '/etc/asterisk/manager.conf': Found
Sep 6 21:41:22 [1;33;40mNOTICE[0;37;40m[20957]: [1;37;40mmanager.c[0;37;40m:[1;37;40m575[0;37;40m [1;37;40mauthenticate[0;37;40m: 127.0.0.1 tried to authenticate with nonexistent user 'remote_mgr'
[1;30;40m == [0;37;40mConnect attempt from '127.0.0.1' unable to authenticate
Beginning asterisk shutdown....
[1;30;40m == [0;37;40mSpawn extension (default, 8600051, 1) exited non-zero on 'IAX2/1000-33'
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mIAX2/1000-33[0;37;40m", "[1;35;40mcall_log.agi|h[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
2006-09-06 21:41:27|call_log.agi|AGI Environment Dump:
2006-09-06 21:41:27|call_log.agi| -- accountcode =
2006-09-06 21:41:27|call_log.agi| -- callerid = unknown
2006-09-06 21:41:27|call_log.agi| -- calleridname = S0609062141048600051
2006-09-06 21:41:27|call_log.agi| -- callingani2 = 0
2006-09-06 21:41:27|call_log.agi| -- callingpres = 0
2006-09-06 21:41:27|call_log.agi| -- callingtns = 0
2006-09-06 21:41:27|call_log.agi| -- callington = 0
2006-09-06 21:41:27|call_log.agi| -- channel = IAX2/1000-33
2006-09-06 21:41:27|call_log.agi| -- context = default
2006-09-06 21:41:27|call_log.agi| -- dnid = unknown
2006-09-06 21:41:27|call_log.agi| -- enhanced = 0.0
2006-09-06 21:41:27|call_log.agi| -- extension = h
2006-09-06 21:41:27|call_log.agi| -- language = en
2006-09-06 21:41:27|call_log.agi| -- priority = 1
2006-09-06 21:41:27|call_log.agi| -- rdnis = unknown
2006-09-06 21:41:27|call_log.agi| -- request = call_log.agi
2006-09-06 21:41:27|call_log.agi| -- type = IAX2
2006-09-06 21:41:27|call_log.agi| -- uniqueid = 1157593264.31
2006-09-06 21:41:27|call_log.agi|AGI Variables: |1157593264.31|IAX2/1000-33|h|IAX2|S0609062141048600051|
2006-09-06 21:41:27|call_log.agi||CALL HUNG UP|
2006-09-06 21:41:27|call_log.agi||DELETE from live_inbound where uniqueid='1157593264.31' and server_ip='127.0.0.1'|
2006-09-06 21:41:27|call_log.agi|+++++ CALL LOG HUNGUP: |1157593264.31|IAX2/1000-33|h|2006-09-06 21:41:27|min: |
[1;30;40m -- [0;37;40mAGI Script call_log.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mIAX2/1000-33[0;37;40m", "[1;35;40mVD_hangup.agi|PRI-----NODEBUG-----0---------------[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
2006-09-06 21:41:27|VD_hangup.agi|Perl Environment Dump:
2006-09-06 21:41:27|VD_hangup.agi|0|PRI-----NODEBUG-----0---------------
2006-09-06 21:41:27|VD_hangup.agi|AGI Environment Dump:
2006-09-06 21:41:27|VD_hangup.agi| -- accountcode =
2006-09-06 21:41:27|VD_hangup.agi| -- callerid = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- calleridname = S0609062141048600051
2006-09-06 21:41:27|VD_hangup.agi| -- callingani2 = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callingpres = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callingtns = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callington = 0
2006-09-06 21:41:27|VD_hangup.agi| -- channel = IAX2/1000-33
2006-09-06 21:41:27|VD_hangup.agi| -- context = default
2006-09-06 21:41:27|VD_hangup.agi| -- dnid = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- enhanced = 0.0
2006-09-06 21:41:27|VD_hangup.agi| -- extension = h
2006-09-06 21:41:27|VD_hangup.agi| -- language = en
2006-09-06 21:41:27|VD_hangup.agi| -- priority = 2
2006-09-06 21:41:27|VD_hangup.agi| -- rdnis = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- request = VD_hangup.agi
2006-09-06 21:41:27|VD_hangup.agi| -- type = IAX2
2006-09-06 21:41:27|VD_hangup.agi| -- uniqueid = 1157593264.31
2006-09-06 21:41:27|VD_hangup.agi|AGI Variables: |1157593264.31|IAX2/1000-33|h|IAX2|S0609062141048600051|
2006-09-06 21:41:27|VD_hangup.agi|DEBUG: NODEBUG
2006-09-06 21:41:27|VD_hangup.agi|VD_hangup : S0609062141048600051 IAX2/1000-33 2 48600051
2006-09-06 21:41:27|VD_hangup.agi||SELECT lead_id,callerid FROM vicidial_auto_calls where uniqueid = '1157593264.31' limit 1;|
2006-09-06 21:41:27|VD_hangup.agi|VD hangup: no VDAC record found: 1157593264.31 S0609062141048600051
[1;30;40m -- [0;37;40mAGI Script VD_hangup.agi completed, returning 0
[1;30;40m -- [0;37;40mHungup 'IAX2/1000-33'
[1;30;40m -- [0;37;40mHungup 'Zap/pseudo-728086434'
[1;30;40m == [0;37;40mSpawn extension (default, 8600051, 1) exited non-zero on 'Zap/1-1'
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mZap/1-1[0;37;40m", "[1;35;40mcall_log.agi|h[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
2006-09-06 21:41:27|call_log.agi|AGI Environment Dump:
2006-09-06 21:41:27|call_log.agi| -- accountcode =
2006-09-06 21:41:27|call_log.agi| -- callerid = unknown
2006-09-06 21:41:27|call_log.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:27|call_log.agi| -- callingani2 = 0
2006-09-06 21:41:27|call_log.agi| -- callingpres = 0
2006-09-06 21:41:27|call_log.agi| -- callingtns = 0
2006-09-06 21:41:27|call_log.agi| -- callington = 0
2006-09-06 21:41:27|call_log.agi| -- channel = Zap/1-1
2006-09-06 21:41:27|call_log.agi| -- context = default
2006-09-06 21:41:27|call_log.agi| -- dnid = unknown
2006-09-06 21:41:27|call_log.agi| -- enhanced = 0.0
2006-09-06 21:41:27|call_log.agi| -- extension = h
2006-09-06 21:41:27|call_log.agi| -- language = en
2006-09-06 21:41:27|call_log.agi| -- priority = 1
2006-09-06 21:41:27|call_log.agi| -- rdnis = unknown
2006-09-06 21:41:27|call_log.agi| -- request = call_log.agi
2006-09-06 21:41:27|call_log.agi| -- type = Zap
2006-09-06 21:41:27|call_log.agi| -- uniqueid = 1157593277.33
2006-09-06 21:41:27|call_log.agi|AGI Variables: |1157593277.33|Zap/1-1|h|Zap|V0906214117000000066|
2006-09-06 21:41:27|call_log.agi||CALL HUNG UP|
2006-09-06 21:41:27|call_log.agi||1157593277.3299999|1157593281|
2006-09-06 21:41:27|call_log.agi|QUERY done: start time = 1157593281 | sec: 6 | min: 0.10 |
2006-09-06 21:41:27|call_log.agi||UPDATE call_log set end_time='2006-09-06 21:41:27',end_epoch='1157593287',length_in_sec=6,length_in_min=' 0.10' where uniqueid='1157593277.33'|
2006-09-06 21:41:27|call_log.agi||DELETE from live_inbound where uniqueid='1157593277.33' and server_ip='127.0.0.1'|
2006-09-06 21:41:27|call_log.agi|+++++ CALL LOG HUNGUP: |1157593277.33|Zap/1-1|h|2006-09-06 21:41:27|min: 0.10|
[1;30;40m -- [0;37;40mAGI Script call_log.agi completed, returning 0
[1;30;40m -- [0;37;40mExecuting [1;36;40mDeadAGI[0;37;40m("[1;35;40mZap/1-1[0;37;40m", "[1;35;40mVD_hangup.agi|PRI-----NODEBUG-----0---------------[0;37;40m") in new stack
[1;30;40m -- [0;37;40mLaunched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
2006-09-06 21:41:27|VD_hangup.agi|Perl Environment Dump:
2006-09-06 21:41:27|VD_hangup.agi|0|PRI-----NODEBUG-----0---------------
2006-09-06 21:41:27|VD_hangup.agi|AGI Environment Dump:
2006-09-06 21:41:27|VD_hangup.agi| -- accountcode =
2006-09-06 21:41:27|VD_hangup.agi| -- callerid = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- calleridname = V0906214117000000066
2006-09-06 21:41:27|VD_hangup.agi| -- callingani2 = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callingpres = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callingtns = 0
2006-09-06 21:41:27|VD_hangup.agi| -- callington = 0
2006-09-06 21:41:27|VD_hangup.agi| -- channel = Zap/1-1
2006-09-06 21:41:27|VD_hangup.agi| -- context = default
2006-09-06 21:41:27|VD_hangup.agi| -- dnid = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- enhanced = 0.0
2006-09-06 21:41:27|VD_hangup.agi| -- extension = h
2006-09-06 21:41:27|VD_hangup.agi| -- language = en
2006-09-06 21:41:27|VD_hangup.agi| -- priority = 2
2006-09-06 21:41:27|VD_hangup.agi| -- rdnis = unknown
2006-09-06 21:41:27|VD_hangup.agi| -- request = VD_hangup.agi
2006-09-06 21:41:27|VD_hangup.agi| -- type = Zap
2006-09-06 21:41:27|VD_hangup.agi| -- uniqueid = 1157593277.33
2006-09-06 21:41:27|VD_hangup.agi|AGI Variables: |1157593277.33|Zap/1-1|h|Zap|V0906214117000000066|
2006-09-06 21:41:27|VD_hangup.agi|DEBUG: NODEBUG
2006-09-06 21:41:27|VD_hangup.agi|VD_hangup : V0906214117000000066 Zap/1-1 2 66
2006-09-06 21:41:27|VD_hangup.agi||SELECT lead_id,callerid FROM vicidial_auto_calls where uniqueid = '1157593277.33' limit 1;|
2006-09-06 21:41:27|VD_hangup.agi|-- VDAC record deleted: |1| |66|1157593277.33|V0906214117000000066|127.0.0.1
2006-09-06 21:41:27|VD_hangup.agi||SELECT start_epoch,status FROM vicidial_log where uniqueid='1157593277.33' and lead_id='66' limit 1;|
2006-09-06 21:41:27|VD_hangup.agi|no VDL record found: 1157593277.33 V0906214117000000066 66 1157593277.33
2006-09-06 21:41:27|VD_hangup.agi||SELECT start_epoch,status,closecallid FROM vicidial_closer_log where lead_id = '66' and call_date > "2006-09-06 15:41:27" order by call_date desc limit 1;|
2006-09-06 21:41:27|VD_hangup.agi|no VDL or VDCL record found: 1157593277.33 V0906214117000000066 66 1157593277.33
[1;30;40m -- [0;37;40mAGI Script VD_hangup.agi completed, returning 0
[1;30;40m -- [0;37;40mHungup 'Zap/1-1'
Executing last minute cleanups
[1;30;40m == [0;37;40mDestroying musiconhold processes
Asterisk cleanly ending (2).
[0m]0;root@asterisk1:/var/log/astguiclient[root@asterisk1 astguiclient]#