No audio response from the * server in voicemail or clock

Asterisk Asterisk Support
1

hi,

i can not hear any output from the asterisk server like my extension number, voice mail prompt or speaking clock. all files are in place and i do not get an error message in the log files at all. i can receive calls and make calls on either the ISDN line or via IAX2. i have seen others with that problem in this forum but it seems that all attempts to solve that problem just don’t work or the topic just never gets closed with a solution.

i am using Asterisk 1.2.23

here the output from a voice mail call which i have in my log file:

log from calling the voice mail system *97

Sep 3 17:32:43 DEBUG[6156] chan_sip.c: Setting NAT on RTP to 524288
Sep 3 17:32:44 DEBUG[6156] chan_sip.c: Stopping retransmission on ‘6d42f354-ccc015d9@10.200.0.2’ of Response 101: Match Found
Sep 3 17:32:44 DEBUG[6156] chan_sip.c: Setting NAT on RTP to 524288
Sep 3 17:32:44 DEBUG[6156] chan_sip.c: Checking SIP call limits for device 121
Sep 3 17:32:44 DEBUG[6156] chan_sip.c: build_route: Contact hop: Jan Home sip:121@10.200.0.2:5060
Sep 3 17:32:44 VERBOSE[6226] logger.c: – Executing Answer(“SIP/121-b7000470”, “”) in new stack
Sep 3 17:32:44 DEBUG[6148] channel.c: Avoiding initial deadlock for 'SIP/121-b7000470’
Sep 3 17:32:44 VERBOSE[6226] logger.c: – Executing Wait(“SIP/121-b7000470”, “1”) in new stack
Sep 3 17:32:44 DEBUG[6156] chan_sip.c: Stopping retransmission on ‘6d42f354-ccc015d9@10.200.0.2’ of Response 102: Match Found
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing Macro(“SIP/121-b7000470”, “user-callerid|”) in new stack
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is 'device’
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is '121’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing NoOp(“SIP/121-b7000470”, “user-callerid: device 121”) in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: Noop
Sep 3 17:32:45 DEBUG[6226] pbx.c: Expression result is '0’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing GotoIf(“SIP/121-b7000470”, “0?report”) in new stack
Sep 3 17:32:45 DEBUG[6226] pbx.c: Not taking any branch
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: GotoIf
Sep 3 17:32:45 DEBUG[6226] pbx.c: Expression result is '0’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing GotoIf(“SIP/121-b7000470”, “0?start”) in new stack
Sep 3 17:32:45 DEBUG[6226] pbx.c: Not taking any branch
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: GotoIf
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is '121’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing Set(“SIP/121-b7000470”, “REALCALLERIDNUM=121”) in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: Set
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing NoOp(“SIP/121-b7000470”, “REALCALLERIDNUM is 121”) in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: NoOp
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is '121’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing Set(“SIP/121-b7000470”, “AMPUSER=121”) in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: Set
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is 'Jan Home’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing Set(“SIP/121-b7000470”, “AMPUSERCIDNAME=Jan Home”) in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: Set
Sep 3 17:32:45 DEBUG[6226] pbx.c: Expression result is '0’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing GotoIf(“SIP/121-b7000470”, “0?report”) in new stack
Sep 3 17:32:45 DEBUG[6226] pbx.c: Not taking any branch
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: GotoIf
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing Set(“SIP/121-b7000470”, “CALLERID(all)=Jan Home <121>”) in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: Set
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is '121’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing Set(“SIP/121-b7000470”, “REALCALLERIDNUM=121”) in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: Set
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing NoOp(“SIP/121-b7000470”, "TTL: ARG1: ") in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: Noop
Sep 3 17:32:45 DEBUG[6226] pbx.c: Expression result is '0’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing GotoIf(“SIP/121-b7000470”, “0?continue”) in new stack
Sep 3 17:32:45 DEBUG[6226] pbx.c: Not taking any branch
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: GotoIf
Sep 3 17:32:45 DEBUG[6226] pbx.c: Expression result is '1’
Sep 3 17:32:45 DEBUG[6226] pbx.c: Expression result is '-1’
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is '64’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing Set(“SIP/121-b7000470”, “__TTL=64”) in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: Set
Sep 3 17:32:45 DEBUG[6226] pbx.c: Expression result is ‘1’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing GotoIf(“SIP/121-b7000470”, “1?continue”) in new stack
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Goto (macro-user-callerid,s,21)
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: GotoIf
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is ‘“Jan Home” <121>‘
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing NoOp(“SIP/121-b7000470”, “Using CallerID “Jan Home” <121>”) in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: NoOp
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is ‘121’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing Macro(“SIP/121-b7000470”, “get-vmcontext|121”) in new stack
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is ‘default’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing Set(“SIP/121-b7000470”, “VMCONTEXT=default”) in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: Set
Sep 3 17:32:45 DEBUG[6226] pbx.c: Expression result is ‘0’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing GotoIf(“SIP/121-b7000470”, “0?200:300”) in new stack
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Goto (macro-get-vmcontext,s,300)
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: GotoIf
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing NoOp(“SIP/121-b7000470”, “”) in new stack
Sep 3 17:32:45 DEBUG[6226] app_macro.c: Executed application: NoOp
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is ‘121’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing MailboxExists(“SIP/121-b7000470”, “121@default”) in new stack
Sep 3 17:32:45 DEBUG[6226] pbx.c: Function result is ‘121’
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Executing VoiceMailMain(“SIP/121-b7000470”, “121@default”) in new stack
Sep 3 17:32:45 DEBUG[6226] channel.c: Scheduling timer at 160 sample intervals
Sep 3 17:32:45 VERBOSE[6226] logger.c: – Playing ‘vm-password’ (language ‘en’)
Sep 3 17:32:49 DEBUG[6156] chan_sip.c: Stopping retransmission on ‘7424b507580e9e3d42a4cdae48389023@10.27.99.10’ of Request 102: Match Found
Sep 3 17:32:50 DEBUG[6226] channel.c: Scheduling timer at 0 sample intervals
Sep 3 17:32:50 WARNING[6226] app_voicemail.c: Unable to read password
Sep 3 17:32:50 DEBUG[6226] pbx.c: Extension *97, priority 106 returned normally even though call was hung up
Sep 3 17:32:50 VERBOSE[6226] logger.c: – Executing Macro(“SIP/121-b7000470”, “hangupcall”) in new stack
Sep 3 17:32:50 VERBOSE[6226] logger.c: – Executing ResetCDR(“SIP/121-b7000470”, “w”) in new stack
Sep 3 17:32:50 DEBUG[6226] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
Sep 3 17:32:50 DEBUG[6226] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,accountcode,uniqueid) VALUES (‘2007-09-03 17:32:44’,’“Jan Home” <121>’,‘121’,’*97’,‘from-internal’, ‘SIP/121-b7000470’,’’,‘VoiceMailMain’,‘121@default’,6,6,‘ANSWERED’,3,’’,‘1188837164.2’)
Sep 3 17:32:50 DEBUG[6226] app_macro.c: Executed application: ResetCDR
Sep 3 17:32:50 VERBOSE[6226] logger.c: – Executing NoCDR(“SIP/121-b7000470”, “”) in new stack
Sep 3 17:32:50 NOTICE[6226] cdr.c: CDR on channel ‘SIP/121-b7000470’ not posted
Sep 3 17:32:50 NOTICE[6226] cdr.c: CDR on channel ‘SIP/121-b7000470’ lacks end
Sep 3 17:32:50 DEBUG[6226] app_macro.c: Executed application: NoCDR
Sep 3 17:32:50 DEBUG[6226] pbx.c: Expression result is '1’
Sep 3 17:32:50 VERBOSE[6226] logger.c: – Executing GotoIf(“SIP/121-b7000470”, “1?skiprg”) in new stack
Sep 3 17:32:50 VERBOSE[6226] logger.c: – Goto (macro-hangupcall,s,6)
Sep 3 17:32:50 DEBUG[6226] app_macro.c: Executed application: GotoIf
Sep 3 17:32:50 DEBUG[6226] pbx.c: Expression result is '1’
Sep 3 17:32:50 VERBOSE[6226] logger.c: – Executing GotoIf(“SIP/121-b7000470”, “1?theend”) in new stack
Sep 3 17:32:50 VERBOSE[6226] logger.c: – Goto (macro-hangupcall,s,9)
Sep 3 17:32:50 DEBUG[6226] app_macro.c: Executed application: GotoIf
Sep 3 17:32:50 VERBOSE[6226] logger.c: – Executing Wait(“SIP/121-b7000470”, “5”) in new stack
Sep 3 17:32:50 VERBOSE[6226] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on ‘SIP/121-b7000470’ in macro 'hangupcall’
Sep 3 17:32:50 VERBOSE[6226] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/121-b7000470’
Sep 3 17:32:50 DEBUG[6226] chan_sip.c: update_call_counter(121) - decrement call limit counter

2

is there nobody out there who has the same problem ? i can’t imagine that i have the only setup with that problem. if anybody can help, please !

regards
Jan

3

Hi Make sure that any zaptel hardware installed is configured and that includes ztdummy.

Ian Plain

4

we do not have and want any zaptel hardware, we have a BRI card which does all the PSTN communication and which works fine. what do i need to configure about the dummy driver/device ? what are the essentials ? any suggestion and help is appreciated.

regards
Jan

5

ztdummy is from what i have read so far also needed for the IAX2 protocol. i an using IAX2 successful with blueface (irish VoIP provider). that would suggest i do not have a ztdummy problem, am i right ?

regards
Jan

6

i have just unloaded the following kernel modules:

rmmod ztdummy
rmmod zttranscode
rmmod zaptel
rmmod crc_ccitt

not sure about the order, but anyway, lsmod does not show them anymnore. now i have voice prompts from the asterisk server AND all sip and IAX2 and BRI is working just fine. i have seen in other forums that asterisk seems to believe that it would have a DSP in the server which is not there because of the ztdummy module. so i just tried this to make sure the asterisk server does not assume to have any zaptel cards. i do not know the real technical background of this now but it works for me.

my setup is

compaq dl580 server, 4 x PIII 700MHz, 1GB Ram
debian etch
Asterisk 1.2.23
freepbx 2.2.3
AVM Fritz! BRI card
IAX2 trunk to blueface behind NAT with static ip mapping
1 Sipura 2100 ATA within LAN
2 Linksys PAP2T ATA behind NAT

regards
Jan