No sound after calls are recorded using Asterisk

kanwaldeep · May 18, 2010, 1:54pm

I am using Asterisk1.2.13 and I have Sangoma A200/Remora FXO/FXS Analog AFT card installed on a Centos machine.

On 2 zap channels, I have phones connected. I am only doing a simple Playback/Recording on both ends in source/destination dialplans. Playing a file at source and recording the same on destination.

I have observed that the call goes through, the recording is done. But when I actually try and hear the recording, I do not hear any audio but only silence.

Any help would be highly appreciated.

~ Kanwal.

indreias · May 20, 2010, 12:14pm

Hello,

Could you post your dialplan and the log for such a call.

HTH,
Ioan

kanwaldeep · May 20, 2010, 12:31pm

Hi Ioan,

Here is the Dialplan for source and destination of the call:

Caller

1, Answer,
2, Wait,15
3, DBput,scout/"""+ prefix +""“caller=true
4, AGI,Sync|”""+ prefix +""“caller|”""+ prefix +""“callee|60|e
5, Playback,”""+ FILE1 +"""
6, Wait,10
7, Hangup,

Callee

And here is the Asterisk log for the call:

May 20 05:18:47 DEBUG[3407] manager.c: Manager received command 'Ping’
May 20 05:18:48 DEBUG[3407] manager.c: Manager received command 'Originate’
May 20 05:18:48 DEBUG[3418] dsp.c: dsp busy pattern set to 0,0
May 20 05:18:48 DEBUG[3418] chan_zap.c: Dialing '16508529643’
May 20 05:18:48 DEBUG[3418] chan_zap.c: Deferring dialing…
May 20 05:18:48 DEBUG[14100] channel.c: Avoiding initial deadlock for 'Zap/7-1’
May 20 05:18:48 DEBUG[3418] chan_zap.c: Exception on 22, channel 7
May 20 05:18:48 DEBUG[3418] chan_zap.c: Got event Hook Transition Complete(12) on channel 7 (index 0)
May 20 05:18:48 DEBUG[25205] manager.c: Manager received command 'Ping’
May 20 05:18:49 DEBUG[3421] manager.c: Manager received command 'Login’
May 20 05:18:49 VERBOSE[3421] logger.c: == Parsing ‘/root/scout.bin/uam/etc/asterisk/manager.conf’: May 20 05:18:49 VERBOSE[3421] logger.c: == Parsing ‘
/root/scout.bin/uam/etc/asterisk/manager.conf’: Found
May 20 05:18:49 DEBUG[3421] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
May 20 05:18:49 DEBUG[3421] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 20 05:18:49 DEBUG[3421] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 20 05:18:49 DEBUG[3421] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
May 20 05:18:49 DEBUG[3421] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 20 05:18:49 DEBUG[3421] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 20 05:18:49 VERBOSE[3421] logger.c: == Manager ‘manager’ logged on from 127.0.0.1
May 20 05:18:50 DEBUG[3407] manager.c: Manager received command 'Ping’
May 20 05:18:50 DEBUG[3421] manager.c: Manager received command 'Ping’
May 20 05:18:50 DEBUG[3418] chan_zap.c: Exception on 22, channel 7
May 20 05:18:50 DEBUG[3418] chan_zap.c: Got event Dial Complete(9) on channel 7 (index 0)
May 20 05:18:50 DEBUG[3418] chan_zap.c: Enabled echo cancellation on channel 7
May 20 05:18:50 DEBUG[3418] chan_zap.c: Engaged echo training on channel 7
May 20 05:18:52 DEBUG[3407] manager.c: Manager received command 'Ping’
May 20 05:18:52 DEBUG[3418] chan_zap.c: Exception on 22, channel 7
May 20 05:18:52 DEBUG[3418] chan_zap.c: Got event Dial Complete(9) on channel 7 (index 0)
May 20 05:18:52 DEBUG[3418] chan_zap.c: Echo cancellation already on
May 20 05:18:52 VERBOSE[3418] logger.c: > Channel Zap/7-1 was answered.
May 20 05:18:52 VERBOSE[25188] logger.c: == Manager ‘manager’ logged off from 127.0.0.1
May 20 05:18:52 VERBOSE[3423] logger.c: – Executing Answer(“Zap/7-1”, “”)
May 20 05:18:52 VERBOSE[3423] logger.c: – Executing Wait(“Zap/7-1”, “15”)
May 20 05:18:53 VERBOSE[25205] logger.c: == Manager ‘manager’ logged off from 127.0.0.1
May 20 05:18:53 DEBUG[3421] manager.c: Manager received command 'Ping’
May 20 05:18:53 DEBUG[3425] manager.c: Manager received command 'Login’
May 20 05:18:53 VERBOSE[3425] logger.c: == Parsing ‘/root/scout.bin/uam/etc/asterisk/manager.conf’: May 20 05:18:53 VERBOSE[3425] logger.c: == Parsing ‘
/root/scout.bin/uam/etc/asterisk/manager.conf’: Found
May 20 05:18:53 DEBUG[3425] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
May 20 05:18:53 DEBUG[3425] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 20 05:18:53 DEBUG[3425] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 20 05:18:53 DEBUG[3425] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
May 20 05:18:53 DEBUG[3425] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 20 05:18:53 DEBUG[3425] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 20 05:18:53 VERBOSE[3425] logger.c: == Manager ‘manager’ logged on from 127.0.0.1
May 20 05:18:56 DEBUG[3425] manager.c: Manager received command 'Ping’
May 20 05:18:56 DEBUG[14107] dsp.c: dsp busy pattern set to 0,0
May 20 05:18:56 VERBOSE[3426] logger.c: – Starting simple switch on 'Zap/5-1’
May 20 05:18:56 VERBOSE[3421] logger.c: == Manager ‘manager’ logged off from 127.0.0.1
May 20 05:18:59 DEBUG[3407] manager.c: Manager received command 'Ping’
May 20 05:18:59 NOTICE[3426] chan_zap.c: Got event 18 (Ring Begin)…
May 20 05:19:01 DEBUG[3425] manager.c: Manager received command 'Ping’
May 20 05:19:01 VERBOSE[14098] logger.c: – Remote UNIX connection
May 20 05:19:01 VERBOSE[3439] logger.c: – Remote UNIX connection disconnected
May 20 05:19:02 NOTICE[3426] chan_zap.c: Got event 2 (Ring/Answered)…
May 20 05:19:02 VERBOSE[3426] logger.c: – Executing Goto(“Zap/5-1”, “default|2501|1”) in new stack
May 20 05:19:02 VERBOSE[3426] logger.c: – Goto (default,2501,1)
May 20 05:19:02 VERBOSE[3426] logger.c: – Executing Answer(“Zap/5-1”, “”)
May 20 05:19:02 DEBUG[3426] chan_zap.c: Took Zap/5-1 off hook
May 20 05:19:02 DEBUG[3426] chan_zap.c: Enabled echo cancellation on channel 5
May 20 05:19:02 DEBUG[3426] chan_zap.c: Engaged echo training on channel 5
May 20 05:19:02 VERBOSE[3426] logger.c: – Executing Wait(“Zap/5-1”, “10”)
May 20 05:19:02 DEBUG[3407] manager.c: Manager received command 'Ping’
May 20 05:19:02 DEBUG[3441] manager.c: Manager received command 'Login’
May 20 05:19:02 VERBOSE[3441] logger.c: == Parsing ‘/root/scout.bin/uam/etc/asterisk/manager.conf’: May 20 05:19:02 VERBOSE[3441] logger.c: == Parsing ‘
/root/scout.bin/uam/etc/asterisk/manager.conf’: Found
May 20 05:19:02 DEBUG[3441] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
May 20 05:19:02 DEBUG[3441] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 20 05:19:02 DEBUG[3441] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 20 05:19:02 DEBUG[3441] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
May 20 05:19:02 DEBUG[3441] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 20 05:19:02 DEBUG[3441] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 20 05:19:02 VERBOSE[3441] logger.c: == Manager ‘manager’ logged on from 127.0.0.1
May 20 05:19:04 DEBUG[3407] manager.c: Manager received command 'Ping’
May 20 05:19:04 DEBUG[3441] manager.c: Manager received command 'Ping’
May 20 05:19:08 VERBOSE[3423] logger.c: – Executing DBput(“Zap/7-1”, “scout/174caller=true”)
May 20 05:19:08 VERBOSE[3423] logger.c: – DBput: family=scout, key=174caller, value=true
May 20 05:19:08 VERBOSE[3423] logger.c: – Executing AGI(“Zap/7-1”, “Sync|174caller|174callee|60|e”)
May 20 05:19:08 VERBOSE[3423] logger.c: – Launched AGI Script /root/scout.bin/uam/var/lib/asterisk/agi-bin/Sync
May 20 05:19:12 DEBUG[3441] manager.c: Manager received command 'Ping’
May 20 05:19:12 VERBOSE[3426] logger.c: – Executing DBput(“Zap/5-1”, “scout/174callee=true”)
May 20 05:19:12 VERBOSE[3426] logger.c: – DBput: family=scout, key=174callee, value=true
May 20 05:19:12 VERBOSE[3426] logger.c: – Executing AGI(“Zap/5-1”, “Sync|174callee|174caller|60|e”)
May 20 05:19:12 VERBOSE[3426] logger.c: – Launched AGI Script /root/scout.bin/uam/var/lib/asterisk/agi-bin/Sync
May 20 05:19:12 VERBOSE[3426] logger.c: – AGI Script Sync completed, returning 0
May 20 05:19:12 VERBOSE[3426] logger.c: – Executing Record(“Zap/5-1”, “/tmp/VoicemailInTeloDuringRingingRecording.wav|0|35”)
May 20 05:19:12 DEBUG[3426] channel.c: Scheduling timer at 160 sample intervals
May 20 05:19:12 VERBOSE[3426] logger.c: – Playing ‘beep’ (language ‘en’)
May 20 05:19:12 VERBOSE[3423] logger.c: – AGI Script Sync completed, returning 0
May 20 05:19:12 VERBOSE[3423] logger.c: – Executing Playback(“Zap/7-1”, “/root/scout.bin/data/35s”)
May 20 05:19:12 DEBUG[3423] channel.c: Scheduling timer at 160 sample intervals
May 20 05:19:12 VERBOSE[3423] logger.c: – Playing ‘/root/scout.bin/data/35s’ (language ‘en’)
May 20 05:19:13 DEBUG[3426] channel.c: Scheduling timer at 0 sample intervals
May 20 05:19:13 DEBUG[3426] channel.c: Scheduling timer at 0 sample intervals
May 20 05:19:13 DEBUG[3426] chan_zap.c: Requested indication 18 on channel Zap/5-1
May 20 05:19:13 DEBUG[3407] manager.c: Manager received command 'Ping’
May 20 05:19:49 VERBOSE[3426] logger.c: – Executing Wait(“Zap/5-1”, “10”)
May 20 05:19:50 DEBUG[3407] manager.c: Manager received command 'Ping’
May 20 05:19:52 DEBUG[3423] channel.c: Scheduling timer at 24 sample intervals
May 20 05:19:52 DEBUG[3423] channel.c: Scheduling timer at 0 sample intervals
May 20 05:19:52 DEBUG[3423] channel.c: Scheduling timer at 0 sample intervals
May 20 05:19:52 VERBOSE[3423] logger.c: – Executing Wait(“Zap/7-1”, “10”)
May 20 05:19:53 DEBUG[3407] manager.c: Manager received command 'Ping’
May 20 05:20:00 VERBOSE[3426] logger.c: – Executing Hangup(“Zap/5-1”, “”)
May 20 05:20:00 VERBOSE[3426] logger.c: == Spawn extension (default, 2501, 7) exited non-zero on 'Zap/5-1’
May 20 05:20:00 DEBUG[3426] chan_zap.c: Hangup: channel: 5 index = 0, normal = 20, callwait = -1, thirdcall = -1
May 20 05:20:00 DEBUG[3426] chan_zap.c: disabled echo cancellation on channel 5
May 20 05:20:00 DEBUG[3426] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/5-1
May 20 05:20:00 DEBUG[3426] chan_zap.c: Updated conferencing on 5, with 0 conference users
May 20 05:20:00 VERBOSE[3426] logger.c: – Hungup 'Zap/5-1’
May 20 05:20:01 VERBOSE[3425] logger.c: == Manager ‘manager’ logged off from 127.0.0.1
May 20 05:20:01 DEBUG[3449] manager.c: Manager received command 'Login’
May 20 05:20:01 VERBOSE[3449] logger.c: == Parsing ‘/root/scout.bin/uam/etc/asterisk/manager.conf’: May 20 05:20:01 VERBOSE[3449] logger.c: == Parsing ‘
/root/scout.bin/uam/etc/asterisk/manager.conf’: Found
May 20 05:20:01 DEBUG[3449] acl.c: 0.0.0.0/0.0.0.0/0.0.0.0 appended to acl for peer
May 20 05:20:01 DEBUG[3449] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 20 05:20:01 DEBUG[3449] acl.c: 127.0.0.1/255.255.255.0/255.255.255.0 appended to acl for peer
May 20 05:20:01 DEBUG[3449] acl.c: ##### Testing 127.0.0.1 with 0.0.0.0
May 20 05:20:01 DEBUG[3449] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 20 05:20:01 DEBUG[3449] acl.c: ##### Testing 127.0.0.1 with 127.0.0.0
May 20 05:20:01 VERBOSE[3449] logger.c: == Manager ‘manager’ logged on from 127.0.0.1
May 20 05:20:02 VERBOSE[14098] logger.c: – Remote UNIX connection
May 20 05:20:02 VERBOSE[3461] logger.c: – Remote UNIX connection disconnected
May 20 05:20:02 DEBUG[3407] manager.c: Manager received command 'Ping’
May 20 05:20:02 VERBOSE[3423] logger.c: – Executing Hangup(“Zap/7-1”, “”)
May 20 05:20:02 VERBOSE[3423] logger.c: == Spawn extension (default, 174, 7) exited non-zero on 'Zap/7-1’
May 20 05:20:02 DEBUG[3423] chan_zap.c: Hangup: channel: 7 index = 0, normal = 22, callwait = -1, thirdcall = -1
May 20 05:20:02 DEBUG[3423] chan_zap.c: disabled echo cancellation on channel 7
May 20 05:20:02 DEBUG[3423] chan_zap.c: Set option TDD MODE, value: OFF(0) on Zap/7-1
May 20 05:20:02 DEBUG[3423] chan_zap.c: Updated conferencing on 7, with 0 conference users
May 20 05:20:02 VERBOSE[3423] logger.c: – Hungup ‘Zap/7-1’

Thanks,
Kanwal

indreias · May 20, 2010, 2:05pm

Could you explain how you made the call? Because I really do not understand.

I see only Chan 7 as FXO and Chan 5 as FXO - or maybe I go wrong.

Please also explain with more details what you are trying to do, how you made your call and what is not working - as what you have posted till now do not reveal much.

HTH,
Ioan