Recordings

All installation and configuration problems and questions

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

Recordings

Postby Op3r » Mon Sep 11, 2006 12:13 pm

Guys,

Here is my setup. 2 asterisk/vicidial and one http/mysql

I encountered a problem when I look at the DONE folder and yes it did have some sort of list of the so called recordings but when I look at the file size it is 0! but on all the manual dial recordings are ok.

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

Postby mflorell » Mon Sep 11, 2006 12:22 pm

What script are you using to mix the recordings?

If you run that script manually do you get any errors?

What do the ORIG files look like?
mflorell
Site Admin
 
Posts: 18387
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida

Postby Op3r » Mon Sep 11, 2006 12:37 pm

The files are there but when I look at the file size I see 0 :(

here's my crontab
#ng mixing for Asterisk run every 5 minutes
1,6,11,16,21,26,31,36,41,46,51,56 * * * 1,2,3,4,5,6 /home/cron/AST_CRON_mix_recordings_BASIC.pl

### keepalive script for Asterisk updaters
* * * * * /home/cron/ADMIN_keepalive_AST_update.pl

### keepalive script for Asterisk manager queue system
* * * * * /home/cron/ADMIN_keepalive_AST_send_listen.pl

### keepalive script for Asterisk VICIDIAL autodial system
* * * * * /home/cron/ADMIN_keepalive_AST_VDautodial.pl

### keepalive script for VICIDIAL remote agents
* * * * * /home/cron/ADMIN_keepalive_AST_VDremote_agents.pl

### kill Hangup script for Asterisk updaters
* * * * * /home/cron/AST_manager_kill_hung_congested.pl

### updater for voicemail
* * * * * /home/cron/AST_vm_update.pl

### updater for conference validator
* * * * * /home/cron/AST_conf_update.pl

### flush queue DB table every hour for entries older than 1 hour
11 * * * * /home/cron/AST_flush_DBqueue.pl -q

### fix the vicidial_agent_log once every hour
33 * * * * /home/cron/AST_cleanup_agent_log.pl

### updater for VICIDIAL hopper
* * * * * /home/cron/AST_VDhopper.pl -q

### adjust the GMT offset for the leads in the vicidial_list table
1 1 * * * /home/cron/ADMIN_adjust_GMTnow_on_leads.pl --debug

### reset several temporary-info tables in the database
2 1 * * * /home/cron/AST_reset_mysql_vars.pl

### optimize the database tables within the asterisk database
3 1 * * * /home/cron/AST_DB_optimize.pl

## adjust time on the server with ntp
30 * * * * /usr/local/bin/ntpdate -u 18.145.0.30 2>/dev/null 1>&2

### VICIDIAL agent time log weekly summary report generation
2 0 * * 0 /home/cron/AST_agent_week.pl
Get paid for US outbound Toll Free calls. PM me.
Op3r
 
Posts: 1432
Joined: Wed Jun 07, 2006 7:53 pm
Location: Manila

Postby mflorell » Mon Sep 11, 2006 12:44 pm

If your filesize of the ORIG files is also 0 then ther is certainly a problem.

Please double -check that both the -in and -out files in ORIG are 0 length.

After that, try recording a call and look at the Asterisk CLI with full debug and see what is shows as happening right after you click to record on vicidial.php.
mflorell
Site Admin
 
Posts: 18387
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida

Postby Op3r » Mon Sep 11, 2006 1:03 pm

Its fixed now

I always thought there is something wrong with the confs but its not. It is the SOX who is acting up. I uninstalled and reinstalled sox and IT WORKS!
Get paid for US outbound Toll Free calls. PM me.
Op3r
 
Posts: 1432
Joined: Wed Jun 07, 2006 7:53 pm
Location: Manila

Postby Op3r » Mon Sep 11, 2006 1:14 pm

I thought it was working but I guess not. When I dial 8309 yes I see the recording but when I do auto dial the recordings are there but its 44.
Get paid for US outbound Toll Free calls. PM me.
Op3r
 
Posts: 1432
Joined: Wed Jun 07, 2006 7:53 pm
Location: Manila

Postby mflorell » Mon Sep 11, 2006 1:32 pm

either the -in or the -out file will show up as just 44 bytes, the other should continue to grow as the recording continues.

If still not working try again with full messages debug on and post the messages logfile of the recording being started.

Also, post results of this command:
df -k
mflorell
Site Admin
 
Posts: 18387
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida

Postby Op3r » Mon Sep 11, 2006 2:07 pm

[root@rheeka DONE]# df -k
Filesystem 1K-blocks Used Available Use% Mounted on
/dev/sda2 25197252 1381700 22535576 6% /
/dev/sda1 101086 16843 79024 18% /boot
none 1037432 0 1037432 0% /dev/shm
/dev/sda5 41357808 394932 38861980 2% /var
[root@rheeka DONE]#


here is the asterisk output

-- Executing MeetMe("SIP/7702-0874d3f8", "8600051") in new stack
== Parsing '/etc/asterisk/meetme.conf': Found
-- Created MeetMe conference 1023 for conference '8600051'
-- Playing 'conf-onlyperson' (language 'en')
== Manager 'sendcron' logged off from 127.0.0.1
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing SetAccount("Local/40019146978980@default-9f0b,2", "unet-acl") i n new stack
-- Executing AGI("Local/40019146978980@default-9f0b,2", "call_log.agi|400191 46978980") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing Dial("Local/40019146978980@default-9f0b,2", "SIP/19146978980@ac l|65|o") in new stack
-- Called 19146978980@acl
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing SetAccount("Local/40013154538000@default-2a39,2", "unet-acl") i n new stack
-- Executing AGI("Local/40013154538000@default-2a39,2", "call_log.agi|400131 54538000") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing Dial("Local/40013154538000@default-2a39,2", "SIP/13154538000@ac l|65|o") in new stack
-- Called 13154538000@acl
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing SetAccount("Local/40015853818841@default-88e5,2", "unet-acl") i n new stack
-- Executing AGI("Local/40015853818841@default-88e5,2", "call_log.agi|400158 53818841") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing Dial("Local/40015853818841@default-88e5,2", "SIP/15853818841@ac l|65|o") in new stack
-- Called 15853818841@acl
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing SetAccount("Local/40018457443074@default-c5e1,2", "unet-acl") i n new stack
-- Executing AGI("Local/40018457443074@default-c5e1,2", "call_log.agi|400184 57443074") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing Dial("Local/40018457443074@default-c5e1,2", "SIP/18457443074@ac l|65|o") in new stack
-- Called 18457443074@acl
-- SIP/acl-08805ef0 is ringing
-- SIP/acl-087e5878 is ringing
-- SIP/acl-08805ef0 answered Local/40013154538000@default-2a39,2
> Channel Local/40013154538000@default-2a39,1 was answered.
== Manager 'sendcron' logged off from 127.0.0.1
-- Executing AGI("Local/40013154538000@default-2a39,1", "call_log.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
== Spawn extension (default, 40013154538000, 3) exited non-zero on 'Local/4001 3154538000@default-2a39,2'
-- Executing DeadAGI("Local/40013154538000@default-2a39,2", "call_log.agi|h" ) in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing AGI("SIP/acl-08805ef0", "agi-VDADtransfer.agi|8365") in new sta ck
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("Local/40013154538000@default-2a39,2", "VD_hangup.agi|h ") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
-- AGI Script VD_hangup.agi completed, returning 0
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing NoCDR("SIP/acl-08805ef0", "") in new stack
Sep 11 15:06:36 WARNING[5128]: cdr.c:443 ast_cdr_free: CDR on channel 'Local/400 13154538000@default-2a39,1' not posted
Sep 11 15:06:36 WARNING[5128]: cdr.c:445 ast_cdr_free: CDR on channel 'Local/400 13154538000@default-2a39,1' lacks end
-- Executing MeetMe("SIP/acl-08805ef0", "8600051") in new stack
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
-- Executing NoCDR("Local/78600051@default-fdb0,2", "") in new stack
Sep 11 15:06:38 WARNING[5140]: cdr.c:443 ast_cdr_free: CDR on channel 'Local/786 00051@default-fdb0,2' not posted
Sep 11 15:06:38 WARNING[5140]: cdr.c:445 ast_cdr_free: CDR on channel 'Local/786 00051@default-fdb0,2' lacks end
-- Executing MeetMe("Local/78600051@default-fdb0,2", "8600051|q") in new sta ck
> Channel Local/78600051@default-fdb0,1 was answered.
-- Executing Answer("Local/78600051@default-fdb0,1", "") in new stack
-- Executing Monitor("Local/78600051@default-fdb0,1", "wav|20060911-120659_3 154538000_edwin") in new stack
-- Executing Hangup("Local/78600051@default-fdb0,1", "") in new stack
== Spawn extension (default, 8310, 3) exited non-zero on 'Local/78600051@defau lt-fdb0,1'
-- Executing DeadAGI("Local/78600051@default-fdb0,1", "call_log.agi|h") in n ew stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("Local/78600051@default-fdb0,1", "VD_hangup.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
-- AGI Script VD_hangup.agi completed, returning 0
== Spawn extension (default, 78600051, 2) exited non-zero on 'Local/78600051@d efault-fdb0,2'
-- Executing DeadAGI("Local/78600051@default-fdb0,2", "call_log.agi|h") in n ew stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("Local/78600051@default-fdb0,2", "VD_hangup.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
-- SIP/acl-087ee460 is ringing
-- AGI Script VD_hangup.agi completed, returning 0
== Manager 'sendcron' logged off from 127.0.0.1
-- SIP/acl-087cb738 is making progress passing it to Local/40018457443074@de fault-c5e1,2
== Parsing '/etc/asterisk/manager.conf': Found
== Manager 'sendcron' logged on from 127.0.0.1
== Spawn extension (default, 8600051, 2) exited non-zero on 'SIP/acl-08805ef0'
-- Executing DeadAGI("SIP/acl-08805ef0", "call_log.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("SIP/acl-08805ef0", "VD_hangup.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
-- AGI Script VD_hangup.agi completed, returning 0
-- SIP/acl-087ee460 answered Local/40019146978980@default-9f0b,2
> Channel Local/40019146978980@default-9f0b,1 was answered.
== Manager 'sendcron' logged off from 127.0.0.1
-- Executing AGI("Local/40019146978980@default-9f0b,1", "call_log.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
== Spawn extension (default, 40019146978980, 3) exited non-zero on 'Local/4001 9146978980@default-9f0b,2'
-- Executing DeadAGI("Local/40019146978980@default-9f0b,2", "call_log.agi|h" ) in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing AGI("SIP/acl-087ee460", "agi-VDADtransfer.agi|8365") in new sta ck
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("Local/40019146978980@default-9f0b,2", "VD_hangup.agi|h ") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
-- AGI Script VD_hangup.agi completed, returning 0
== Manager 'sendcron' logged off from 127.0.0.1
-- SIP/acl-087e5878 answered Local/40015853818841@default-88e5,2
> Channel Local/40015853818841@default-88e5,1 was answered.
== Manager 'sendcron' logged off from 127.0.0.1
-- Executing AGI("Local/40015853818841@default-88e5,1", "call_log.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
== Spawn extension (default, 40015853818841, 3) exited non-zero on 'Local/4001 5853818841@default-88e5,2'
-- Executing DeadAGI("Local/40015853818841@default-88e5,2", "call_log.agi|h" ) in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing AGI("SIP/acl-087e5878", "agi-VDADtransfer.agi|8365") in new sta ck
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("Local/40015853818841@default-88e5,2", "VD_hangup.agi|h ") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
-- AGI Script VD_hangup.agi completed, returning 0
-- SIP/acl-087cb738 answered Local/40018457443074@default-c5e1,2
> Channel Local/40018457443074@default-c5e1,1 was answered.
== Manager 'sendcron' logged off from 127.0.0.1
-- Executing AGI("Local/40018457443074@default-c5e1,1", "call_log.agi|8365") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
== Spawn extension (default, 40018457443074, 3) exited non-zero on 'Local/4001 8457443074@default-c5e1,2'
-- Executing DeadAGI("Local/40018457443074@default-c5e1,2", "call_log.agi|h" ) in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing AGI("SIP/acl-087cb738", "agi-VDADtransfer.agi|8365") in new sta ck
-- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("Local/40018457443074@default-c5e1,2", "VD_hangup.agi|h ") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
-- AGI Script VD_hangup.agi completed, returning 0
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing Answer("SIP/acl-087ee460", "") in new stack
-- Executing Playback("SIP/acl-087ee460", "vm-goodbye") in new stack
-- Playing 'vm-goodbye' (language 'en')
-- Executing Hangup("SIP/acl-087ee460", "") in new stack
== Spawn extension (default, 8307, 3) exited non-zero on 'SIP/acl-087ee460'
-- Executing DeadAGI("SIP/acl-087ee460", "call_log.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("SIP/acl-087ee460", "VD_hangup.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
-- AGI Script VD_hangup.agi completed, returning 0
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing Answer("SIP/acl-087e5878", "") in new stack
-- Executing Playback("SIP/acl-087e5878", "vm-goodbye") in new stack
-- Playing 'vm-goodbye' (language 'en')
-- AGI Script agi-VDADtransfer.agi completed, returning 0
-- Executing Answer("SIP/acl-087cb738", "") in new stack
-- Executing Playback("SIP/acl-087cb738", "vm-goodbye") in new stack
-- Playing 'vm-goodbye' (language 'en')
-- Executing Hangup("SIP/acl-087e5878", "") in new stack
== Spawn extension (default, 8307, 3) exited non-zero on 'SIP/acl-087e5878'
-- Executing DeadAGI("SIP/acl-087e5878", "call_log.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("SIP/acl-087e5878", "VD_hangup.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
-- AGI Script VD_hangup.agi completed, returning 0
-- Executing Hangup("SIP/acl-087cb738", "") in new stack
== Spawn extension (default, 8307, 3) exited non-zero on 'SIP/acl-087cb738'
-- Executing DeadAGI("SIP/acl-087cb738", "call_log.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
-- AGI Script call_log.agi completed, returning 0
-- Executing DeadAGI("SIP/acl-087cb738", "VD_hangup.agi|h") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
Get paid for US outbound Toll Free calls. PM me.
Op3r
 
Posts: 1432
Joined: Wed Jun 07, 2006 7:53 pm
Location: Manila

Postby Op3r » Mon Sep 11, 2006 2:09 pm

heres some more

[root@rheeka monitor]# ls
20060911-120659_3154538000_edwin-in.wav 20060911-120659_3154538000_edwin-out.wav DONE ORIG
[root@rheeka monitor]# ls -l
total 148
-rw-r--r-- 1 root root 44 Sep 11 15:06 20060911-120659_3154538000_edwin-in.wav
-rw-r--r-- 1 root root 44 Sep 11 15:06 20060911-120659_3154538000_edwin-out.wav
drwxr-xr-x 2 root root 49152 Sep 11 14:11 DONE
Get paid for US outbound Toll Free calls. PM me.
Op3r
 
Posts: 1432
Joined: Wed Jun 07, 2006 7:53 pm
Location: Manila

Postby mflorell » Mon Sep 11, 2006 2:38 pm

can you post the debug messages from the /var/log/asterisk/messages file for the exact same time period?
mflorell
Site Admin
 
Posts: 18387
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida

Postby Op3r » Mon Sep 11, 2006 2:48 pm

I just simulate a call here it goes

Sep 11 15:46:45 VERBOSE[8130] logger.c: == Spawn extension (default, 78600051, 2) exited non-zero on 'Local/78600051@default-314f,2'
Sep 11 15:46:45 VERBOSE[8130] logger.c: -- Executing DeadAGI("Local/78600051@default-314f,2", "call_log.agi|h") in new stack
Sep 11 15:46:45 VERBOSE[8130] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
Sep 11 15:46:45 VERBOSE[8137] logger.c: -- AGI Script call_log.agi completed, returning 0
Sep 11 15:46:45 VERBOSE[8137] logger.c: -- Executing AGI("SIP/acl-087698e8", "agi-VDADtransfer.agi|8365") in new stack
Sep 11 15:46:45 VERBOSE[8137] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
Sep 11 15:46:45 VERBOSE[8090] logger.c: -- AGI Script call_log.agi completed, returning 0
Sep 11 15:46:45 VERBOSE[8090] logger.c: -- Executing DeadAGI("Local/40015853426790@default-0423,2", "VD_hangup.agi|h") in new stack
Sep 11 15:46:45 VERBOSE[8090] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
Sep 11 15:46:45 VERBOSE[8130] logger.c: -- AGI Script call_log.agi completed, returning 0
Sep 11 15:46:45 VERBOSE[8130] logger.c: -- Executing DeadAGI("Local/78600051@default-314f,2", "VD_hangup.agi|h") in new stack
Sep 11 15:46:45 VERBOSE[8130] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
Sep 11 15:46:45 VERBOSE[8090] logger.c: -- AGI Script VD_hangup.agi completed, returning 0
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is '"V0911154636000141072" <800TRYVOIP>'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is '800TRYVOIP'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is '40015853426790'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is 'default'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is 'Local/40015853426790@default-0423,2'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is 'SIP/acl-087698e8'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is 'DeadAGI'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is 'VD_hangup.agi|h'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is '2006-09-11 15:46:36'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is '2006-09-11 15:46:45'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is '2006-09-11 15:46:45'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is '9'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is '0'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is 'ANSWERED'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is 'DOCUMENTATION'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is 'unet-acl'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is '1158003996.3047'
Sep 11 15:46:45 DEBUG[8090] pbx.c: Function result is '(null)'
Sep 11 15:46:45 VERBOSE[8130] logger.c: -- AGI Script VD_hangup.agi completed, returning 0
Sep 11 15:46:45 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:46 DEBUG[3739] manager.c: Manager received command 'Command'
Sep 11 15:46:46 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:46 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:47 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:47 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:47 DEBUG[3047] chan_sip.c: Stopping retransmission on '15a55e4a080b88d47ed50d6f69c6c7c4@125.212.36.244' of Request 102: Match Found
Sep 11 15:46:48 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:48 DEBUG[3047] chan_sip.c: Stopping retransmission on '6ce5749067b26f1c5d359d1506bb11dc@125.212.36.244' of Request 102: Match Found
Sep 11 15:46:48 DEBUG[8128] manager.c: Manager received command 'Logoff'
Sep 11 15:46:48 VERBOSE[8128] logger.c: == Manager 'sendcron' logged off from 127.0.0.1
Sep 11 15:46:48 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:49 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:49 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:49 DEBUG[3047] chan_sip.c: Stopping retransmission on '3857ff55123d843e65b0205c72c38ac2@125.212.36.244' of Request 102: Match Found
Sep 11 15:46:50 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:50 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:50 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:51 DEBUG[3047] chan_sip.c: Acked pending invite 102
Sep 11 15:46:51 DEBUG[3047] chan_sip.c: Stopping retransmission on '02d366e131976c8716e2b4dd46effb55@125.212.36.244' of Request 102: Match Found
Sep 11 15:46:51 DEBUG[3047] chan_sip.c: build_route: Contact hop: <sip:17166813444@64.124.221.10:5060;transport=udp>
Sep 11 15:46:51 VERBOSE[8101] logger.c: -- SIP/acl-088082a8 answered Local/40017166813444@default-e15a,2
Sep 11 15:46:51 DEBUG[3019] channel.c: Avoiding initial deadlock for 'Local/40017166813444@default-e15a,2'
Sep 11 15:46:51 VERBOSE[8098] logger.c: > Channel Local/40017166813444@default-e15a,1 was answered.
Sep 11 15:46:51 DEBUG[8098] manager.c: Manager received command 'Logoff'
Sep 11 15:46:51 VERBOSE[8098] logger.c: == Manager 'sendcron' logged off from 127.0.0.1
Sep 11 15:46:51 VERBOSE[8153] logger.c: -- Executing AGI("Local/40017166813444@default-e15a,1", "call_log.agi|8365") in new stack
Sep 11 15:46:51 VERBOSE[8153] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
Sep 11 15:46:51 DEBUG[8101] channel.c: Planning to masquerade channel SIP/acl-088082a8 into the structure of Local/40017166813444@default-e15a,1
Sep 11 15:46:51 DEBUG[8101] channel.c: Done planning to masquerade channel SIP/acl-088082a8 into the structure of Local/40017166813444@default-e15a,1
Sep 11 15:46:51 DEBUG[8101] chan_local.c: Not posting to queue since already masked on 'Local/40017166813444@default-e15a,2'
Sep 11 15:46:51 DEBUG[8153] channel.c: Got clone lock for masquerade on 'SIP/acl-088082a8' at 0x87fa66c
Sep 11 15:46:51 DEBUG[8153] channel.c: Putting channel SIP/acl-088082a8 in 64/64 formats
Sep 11 15:46:51 DEBUG[8153] channel.c: Released clone lock on 'Local/40017166813444@default-e15a,1<ZOMBIE>'
Sep 11 15:46:51 DEBUG[8153] channel.c: Done Masquerading SIP/acl-088082a8 (6)
Sep 11 15:46:51 DEBUG[8101] channel.c: Didn't get a frame from channel: Local/40017166813444@default-e15a,2
Sep 11 15:46:51 DEBUG[8101] channel.c: Bridge stops bridging channels Local/40017166813444@default-e15a,2 and Local/40017166813444@default-e15a,1<ZOMBIE>
Sep 11 15:46:51 DEBUG[8101] app_dial.c: Exiting with DIALSTATUS=ANSWER.
Sep 11 15:46:51 VERBOSE[8101] logger.c: == Spawn extension (default, 40017166813444, 3) exited non-zero on 'Local/40017166813444@default-e15a,2'
Sep 11 15:46:51 VERBOSE[8101] logger.c: -- Executing DeadAGI("Local/40017166813444@default-e15a,2", "call_log.agi|h") in new stack
Sep 11 15:46:51 VERBOSE[8101] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
Sep 11 15:46:51 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:51 VERBOSE[8153] logger.c: -- AGI Script call_log.agi completed, returning 0
Sep 11 15:46:51 VERBOSE[8153] logger.c: -- Executing AGI("SIP/acl-088082a8", "agi-VDADtransfer.agi|8365") in new stack
Sep 11 15:46:51 VERBOSE[8153] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/agi-VDADtransfer.agi
Sep 11 15:46:51 VERBOSE[8101] logger.c: -- AGI Script call_log.agi completed, returning 0
Sep 11 15:46:51 VERBOSE[8101] logger.c: -- Executing DeadAGI("Local/40017166813444@default-e15a,2", "VD_hangup.agi|h") in new stack
Sep 11 15:46:51 VERBOSE[8101] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
Sep 11 15:46:51 VERBOSE[8101] logger.c: -- AGI Script VD_hangup.agi completed, returning 0
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is '"V0911154636000141074" <800TRYVOIP>'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is '800TRYVOIP'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is '40017166813444'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is 'default'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is 'Local/40017166813444@default-e15a,2'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is 'SIP/acl-088082a8'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is 'DeadAGI'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is 'VD_hangup.agi|h'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is '2006-09-11 15:46:36'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is '2006-09-11 15:46:51'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is '2006-09-11 15:46:51'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is '15'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is '0'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is 'ANSWERED'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is 'DOCUMENTATION'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is 'unet-acl'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is '1158003996.3053'
Sep 11 15:46:51 DEBUG[8101] pbx.c: Function result is '(null)'
Sep 11 15:46:51 VERBOSE[8137] logger.c: -- AGI Script agi-VDADtransfer.agi completed, returning 0
Sep 11 15:46:51 VERBOSE[8137] logger.c: -- Executing Answer("SIP/acl-087698e8", "") in new stack
Sep 11 15:46:51 VERBOSE[8137] logger.c: -- Executing Playback("SIP/acl-087698e8", "vm-goodbye") in new stack
Sep 11 15:46:51 DEBUG[8137] channel.c: Scheduling timer at 160 sample intervals
Sep 11 15:46:51 VERBOSE[8137] logger.c: -- Playing 'vm-goodbye' (language 'en')
Sep 11 15:46:51 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:52 DEBUG[8165] manager.c: Manager received command 'Login'
Sep 11 15:46:52 VERBOSE[8165] logger.c: == Parsing '/etc/asterisk/manager.conf': Sep 11 15:46:52 VERBOSE[8165] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
Sep 11 15:46:52 VERBOSE[8165] logger.c: == Manager 'sendcron' logged on from 127.0.0.1
Sep 11 15:46:52 DEBUG[8165] manager.c: Manager received command 'Originate'
Sep 11 15:46:52 VERBOSE[8167] logger.c: -- Executing SetAccount("Local/40018455651665@default-d317,2", "unet-acl") in new stack
Sep 11 15:46:52 VERBOSE[8167] logger.c: -- Executing AGI("Local/40018455651665@default-d317,2", "call_log.agi|40018455651665") in new stack
Sep 11 15:46:52 VERBOSE[8167] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
Sep 11 15:46:52 VERBOSE[8167] logger.c: -- AGI Script call_log.agi completed, returning 0
Sep 11 15:46:52 VERBOSE[8167] logger.c: -- Executing Dial("Local/40018455651665@default-d317,2", "SIP/18455651665@acl|65|o") in new stack
Sep 11 15:46:52 DEBUG[8167] chan_sip.c: Setting NAT on RTP to 0
Sep 11 15:46:52 DEBUG[8167] chan_sip.c: Outgoing Call for 18455651665
Sep 11 15:46:52 VERBOSE[8167] logger.c: -- Called 18455651665@acl
Sep 11 15:46:52 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:52 DEBUG[8171] manager.c: Manager received command 'Login'
Sep 11 15:46:52 VERBOSE[8171] logger.c: == Parsing '/etc/asterisk/manager.conf': Sep 11 15:46:52 VERBOSE[8171] logger.c: == Parsing '/etc/asterisk/manager.conf': Found
Sep 11 15:46:52 VERBOSE[8171] logger.c: == Manager 'sendcron' logged on from 127.0.0.1
Sep 11 15:46:52 DEBUG[8171] manager.c: Manager received command 'Hangup'
Sep 11 15:46:52 DEBUG[3047] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2c6ae650106d5e4a6e48585a40e7792e@125.212.36.244' Request 102: Found
Sep 11 15:46:52 VERBOSE[8115] logger.c: == Spawn extension (default, 8600051, 2) exited non-zero on 'SIP/acl-087e7c30'
Sep 11 15:46:52 VERBOSE[8115] logger.c: -- Executing DeadAGI("SIP/acl-087e7c30", "call_log.agi|h") in new stack
Sep 11 15:46:52 VERBOSE[8115] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
Sep 11 15:46:52 DEBUG[8137] channel.c: Scheduling timer at 0 sample intervals
Sep 11 15:46:52 DEBUG[8137] channel.c: Scheduling timer at 0 sample intervals
Sep 11 15:46:52 VERBOSE[8137] logger.c: -- Executing Hangup("SIP/acl-087698e8", "") in new stack
Sep 11 15:46:52 VERBOSE[8137] logger.c: == Spawn extension (default, 8307, 3) exited non-zero on 'SIP/acl-087698e8'
Sep 11 15:46:52 VERBOSE[8137] logger.c: -- Executing DeadAGI("SIP/acl-087698e8", "call_log.agi|h") in new stack
Sep 11 15:46:52 VERBOSE[8137] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
Sep 11 15:46:52 VERBOSE[8115] logger.c: -- AGI Script call_log.agi completed, returning 0
Sep 11 15:46:52 VERBOSE[8115] logger.c: -- Executing DeadAGI("SIP/acl-087e7c30", "VD_hangup.agi|h") in new stack
Sep 11 15:46:52 VERBOSE[8115] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
Sep 11 15:46:52 VERBOSE[8137] logger.c: -- AGI Script call_log.agi completed, returning 0
Sep 11 15:46:52 VERBOSE[8137] logger.c: -- Executing DeadAGI("SIP/acl-087698e8", "VD_hangup.agi|h") in new stack
Sep 11 15:46:52 VERBOSE[8137] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
Sep 11 15:46:52 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:52 VERBOSE[8115] logger.c: -- AGI Script VD_hangup.agi completed, returning 0
Sep 11 15:46:52 DEBUG[8115] chan_sip.c: update_call_counter(17166834411) - decrement call limit counter
Sep 11 15:46:52 VERBOSE[8137] logger.c: -- AGI Script VD_hangup.agi completed, returning 0
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is 'V0911154636000141072'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is '(null)'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is '8307'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is 'default'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is 'Local/40015853426790@default-0423,1'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is '(null)'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is 'DeadAGI'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is 'VD_hangup.agi|h'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is '2006-09-11 15:46:45'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is '2006-09-11 15:46:51'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is '2006-09-11 15:46:52'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is '7'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is '1'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is 'ANSWERED'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is 'DOCUMENTATION'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is '(null)'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is '1158003996.3046'
Sep 11 15:46:52 DEBUG[8137] pbx.c: Function result is '(null)'
Sep 11 15:46:52 DEBUG[8137] chan_sip.c: update_call_counter(15853426790) - decrement call limit counter
Sep 11 15:46:53 DEBUG[3047] chan_sip.c: Stopping retransmission on '268aa2162e24580e24be4d630ecd9f60@125.212.36.244' of Request 103: Match Found
Sep 11 15:46:53 DEBUG[3047] chan_sip.c: Stopping retransmission on '2892f8f239fa942b3785012d297a457d@125.212.36.244' of Request 103: Match Found
Sep 11 15:46:53 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:53 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:54 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:54 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:55 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:55 DEBUG[8171] manager.c: Manager received command 'Logoff'
Sep 11 15:46:55 VERBOSE[8171] logger.c: == Manager 'sendcron' logged off from 127.0.0.1
Sep 11 15:46:55 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:55 DEBUG[3047] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '001955b03f7062490afd21dd64a3a427@125.212.36.244' Request 102: Found
Sep 11 15:46:55 VERBOSE[8096] logger.c: -- SIP/acl-087f5090 is making progress passing it to Local/40017186650800@default-26c3,2
Sep 11 15:46:55 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:56 DEBUG[3047] chan_sip.c: (Provisional) Stopping retransmission (but retaining packet) on '2c6ae650106d5e4a6e48585a40e7792e@125.212.36.244' Request 102: Found
Sep 11 15:46:56 VERBOSE[8167] logger.c: -- SIP/acl-087f0398 is making progress passing it to Local/40018455651665@default-d317,2
Sep 11 15:46:56 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:56 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:57 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:57 VERBOSE[8153] logger.c: -- AGI Script agi-VDADtransfer.agi completed, returning 0
Sep 11 15:46:57 VERBOSE[8153] logger.c: -- Executing Answer("SIP/acl-088082a8", "") in new stack
Sep 11 15:46:57 VERBOSE[8153] logger.c: -- Executing Playback("SIP/acl-088082a8", "vm-goodbye") in new stack
Sep 11 15:46:57 DEBUG[8153] channel.c: Scheduling timer at 160 sample intervals
Sep 11 15:46:57 VERBOSE[8153] logger.c: -- Playing 'vm-goodbye' (language 'en')
Sep 11 15:46:57 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:58 DEBUG[3758] manager.c: Manager received command 'Command'
Sep 11 15:46:58 DEBUG[8153] channel.c: Scheduling timer at 0 sample intervals
Sep 11 15:46:58 DEBUG[8153] channel.c: Scheduling timer at 0 sample intervals
Sep 11 15:46:58 VERBOSE[8153] logger.c: -- Executing Hangup("SIP/acl-088082a8", "") in new stack
Sep 11 15:46:58 VERBOSE[8153] logger.c: == Spawn extension (default, 8307, 3) exited non-zero on 'SIP/acl-088082a8'
Sep 11 15:46:58 VERBOSE[8153] logger.c: -- Executing DeadAGI("SIP/acl-088082a8", "call_log.agi|h") in new stack
Sep 11 15:46:58 VERBOSE[8153] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/call_log.agi
Sep 11 15:46:58 VERBOSE[8153] logger.c: -- AGI Script call_log.agi completed, returning 0
Sep 11 15:46:58 VERBOSE[8153] logger.c: -- Executing DeadAGI("SIP/acl-088082a8", "VD_hangup.agi|h") in new stack
Sep 11 15:46:58 VERBOSE[8153] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/VD_hangup.agi
Sep 11 15:46:58 VERBOSE[8153] logger.c: -- AGI Script VD_hangup.agi completed, returning 0
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is 'V0911154636000141074'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is '(null)'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is '8307'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is 'default'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is 'Local/40017166813444@default-e15a,1'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is '(null)'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is 'DeadAGI'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is 'VD_hangup.agi|h'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is '2006-09-11 15:46:51'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is '2006-09-11 15:46:57'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is '2006-09-11 15:46:58'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is '7'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is '1'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is 'ANSWERED'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is 'DOCUMENTATION'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is '(null)'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is '1158003996.3052'
Sep 11 15:46:58 DEBUG[8153] pbx.c: Function result is '(null)'
Sep 11 15:46:58 DEBUG[8153] chan_sip.c: update_call_counter(17166813444) - decrement call limit counter
Get paid for US outbound Toll Free calls. PM me.
Op3r
 
Posts: 1432
Joined: Wed Jun 07, 2006 7:53 pm
Location: Manila

Postby mflorell » Mon Sep 11, 2006 3:07 pm

I didn't see any attempts to call 8310 in that messages log. Did you click to start recording in vicidial.php during the time that log is from?
mflorell
Site Admin
 
Posts: 18387
Joined: Wed Jun 07, 2006 2:45 pm
Location: Florida

Postby Op3r » Mon Sep 11, 2006 6:35 pm

I got it figured out.

My extensions.conf is lets say f#$K!

the reason why its not recording is i customize it to much that I saw the context being repeated over and over again.


Thanks for the help man
Get paid for US outbound Toll Free calls. PM me.
Op3r
 
Posts: 1432
Joined: Wed Jun 07, 2006 7:53 pm
Location: Manila


Return to Support

Who is online

Users browsing this forum: Bing [Bot] and 59 guests