Asterisk - No Playback Audio

[sators]

Just installed LinuxMCE 710, fresh install from DVD.  Got my Cisco 7960 hooked up and able to call outside to my cell phone, and back from my cell phone to the Cisco. 

The problem I'm having is that I don't hear any audio from Asterisk playback files.  I.e. when I dial voicemail, I don't hear any prompts over the phone.  Here's the brief asterisk log:

    -- Goto (macro-user-callerid,s,23)
    -- Executing [s@macro-user-callerid:23] NoOp("SIP/201-00764880", "Using CallerID "pl_30" <201>") in new stack
    -- Executing [*97@from-internal:4] Macro("SIP/201-00764880", "get-vmcontext|201") in new stack
    -- Executing [s@macro-get-vmcontext:1] Set("SIP/201-00764880", "VMCONTEXT=novm") in new stack
    -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/201-00764880", "0?200:300") in new stack
    -- Goto (macro-get-vmcontext,s,300)
    -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/201-00764880", "") in new stack
    -- Executing [*97@from-internal:5] MailboxExists("SIP/201-00764880", "201@novm") in new stack
    -- Executing [*97@from-internal:6] GotoIf("SIP/201-00764880", "0?mbexist") in new stack
    -- Executing [*97@from-internal:7] VoiceMailMain("SIP/201-00764880", "") in new stack
    -- <SIP/201-00764880> Playing 'vm-login' (language 'en')

At the "Playing 'vm-login' line, I am used to standard Asterisk installations where that would be the point I would hear the .wav/.gsm prompt, but I hear nothing on my phone.  Any thoughts?

[dlewis]

Set verbose 10 and show the log. Also, what codecs are you using?

[sators]

I do have a sound card installed and it is functioning properly, playing back audio from the setup videos as well as Myth.

Here is the log at verbose 10.  You can see towards the end where it plays vm-login and I let it go for a minute before hanging up, heard nothing.  Codecs are set to allow=all cause that's the first thing I thought of:

[May 18 10:58:34] VERBOSE[25967] logger.c:   == Spawn extension (macro-outisbusy, s, 1) exited non-zero on 'SIP/201-0088a3a0' $
[May 18 10:58:34] VERBOSE[25967] logger.c:   == Spawn extension (macro-outisbusy, s, 1) exited non-zero on 'SIP/201-0088a3a0'
[May 18 10:58:39] VERBOSE[26252] logger.c:     -- Executing [*97@from-internal:1] Answer("SIP/201-00767b40", "") in new stack
[May 18 10:58:39] VERBOSE[26252] logger.c:     -- Executing [*97@from-internal:2] Wait("SIP/201-00767b40", "1") in new stack
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [*97@from-internal:3] Macro("SIP/201-00767b40", "user-callerid|") $
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:1] NoOp("SIP/201-00767b40", "user-callerid:$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: Noop
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:2] Set("SIP/201-00767b40", "AMPUSER=201") i$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: Set
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:3] GotoIf("SIP/201-00767b40", "0?report") i$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: GotoIf
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:4] GotoIf("SIP/201-00767b40", "0?start") in$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: GotoIf
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/201-00767b40", "REALCALLERIDNUM$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: Set
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:6] NoOp("SIP/201-00767b40", "REALCALLERIDNU$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: NoOp
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:7] Set("SIP/201-00767b40", "AMPUSER=201") i$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: Set
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:8] Set("SIP/201-00767b40", "AMPUSERCIDNAME=$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: Set
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:9] GotoIf("SIP/201-00767b40", "0?report") i$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: GotoIf
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:10] Set("SIP/201-00767b40", "AMPUSERCID=201$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: Set
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:11] Set("SIP/201-00767b40", "CALLERID(all)=$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: Set
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:12] Set("SIP/201-00767b40", "REALCALLERIDNU$
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:13] NoOp("SIP/201-00767b40", "TTL:  ARG1: "$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: Noop
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:14] GotoIf("SIP/201-00767b40", "0?continue"$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: GotoIf
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:15] Set("SIP/201-00767b40", "__TTL=64") in $
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: Set
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:16] GotoIf("SIP/201-00767b40", "1?continue"$
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Goto (macro-user-callerid,s,23)
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: GotoIf
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-user-callerid:23] NoOp("SIP/201-00767b40", "Using CallerI$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: NoOp
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [*97@from-internal:4] Macro("SIP/201-00767b40", "get-vmcontext|201$
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-get-vmcontext:1] Set("SIP/201-00767b40", "VMCONTEXT=novm"$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: Set
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/201-00767b40", "0?200:300") $
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Goto (macro-get-vmcontext,s,300)
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: GotoIf
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/201-00767b40", "") in new st$
[May 18 10:58:40] DEBUG[26252] app_macro.c: Executed application: NoOp
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [*97@from-internal:5] MailboxExists("SIP/201-00767b40", "201@novm"$
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [*97@from-internal:6] GotoIf("SIP/201-00767b40", "0?mbexist") in n$
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- Executing [*97@from-internal:7] VoiceMailMain("SIP/201-00767b40", "") in new$
[May 18 10:58:40] VERBOSE[26252] logger.c:     -- <SIP/201-00767b40> Playing 'vm-login' (language 'en')
[May 18 10:59:38] WARNING[26252] app_voicemail.c: Couldn't read username
[May 18 10:59:38] VERBOSE[26252] logger.c:     -- Executing [h@from-internal:1] Macro("SIP/201-00767b40", "hangupcall") in new$
[May 18 10:59:38] VERBOSE[26252] logger.c:     -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/201-00767b40", "w") in new st$
[May 18 10:59:38] DEBUG[26252] app_macro.c: Executed application: ResetCDR
[May 18 10:59:38] VERBOSE[26252] logger.c:     -- Executing [s@macro-hangupcall:2] NoCDR("SIP/201-00767b40", "") in new stack
[May 18 10:59:38] DEBUG[26252] app_macro.c: Executed application: NoCDR
[May 18 10:59:38] VERBOSE[26252] logger.c:     -- Executing [s@macro-hangupcall:3] GotoIf("SIP/201-00767b40", "1?skiprg") in n$
[May 18 10:59:38] VERBOSE[26252] logger.c:     -- Goto (macro-hangupcall,s,6)
[May 18 10:59:38] DEBUG[26252] app_macro.c: Executed application: GotoIf
[May 18 10:59:38] VERBOSE[26252] logger.c:     -- Executing [s@macro-hangupcall:6] GotoIf("SIP/201-00767b40", "1?skipblkvm") i$
[May 18 10:59:38] VERBOSE[26252] logger.c:     -- Goto (macro-hangupcall,s,9)
[May 18 10:59:38] DEBUG[26252] app_macro.c: Executed application: GotoIf
May 18 10:59:38] VERBOSE[26252] logger.c:     -- Executing [s@macro-hangupcall:11] Hangup("SIP/201-00767b40", "") in new stack
[May 18 10:59:38] VERBOSE[26252] logger.c:   == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/201-00767b40$
[May 18 10:59:38] VERBOSE[26252] logger.c:   == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/201-00767b40'
[May 18 11:01:27] VERBOSE[25805] logger.c:     -- Remote UNIX connection disconnected
[May 18 11:01:42] WARNING[8774] chan_sip.c: Remote host can't match request NOTIFY to call '3375f127574e717e538bfafe29f729e9@1$

[dlewis]

I haven't encountered this before, so I spoke to a few people about this. They suggest it might be the codecs... Try make it the following:

Code: [Select]

disallow=all
allow=g722
allow=g729
allow=g723
allow=g726
allow=alaw
allow=gsm
allow=speex
allow=ilbc
allow=ulaw
allow=h261
allow=h263
allow=h263p
allow=h264
Let me know if it works

[sators]

Put those codecs into sip.conf, restarted asterisk, tried calling voicemail from a Cisco 7960 and a softphone on the LinuxMCE server itself and had the same result - no audio.

[dlewis]

Try opening ports UDP (NOT TCP) ports 10001-20000 (if they aren't open). Also, if you haven't done so already, create an /etc/asterisk/sip_nat.conf file with AT LEAST these three lines: 1) nat=yes 2) externip=your.external.IPaddess (or externhost=your.external.hostname) 3) localnet=192.168.0.80/24.

Example for sip_nat.conf:

Code: [Select]

nat=yes
externhost=yourhostname.yourhostname.com
localnet=192.168.0.80/24


[dlewis]

Also, send a print out of the following commands: 'sip debug' and 'sip show channels'