No Audio on remote Aastra phones

[FormosDerek]

I have looked at other similar posts but I have tried everything they have done but no use

Just went live with fresh PIAF server and all is working great except my remote phones which were working on my old Asterisk 1.2 system have no audio. Like others I've seen ringing eother way works great but no audio either way when picked up. I will try to give as much info as possible.

Her is my system status
PBX in a Flash Version 1.3 Daemon Status
********************************************************************
* Asterisk * ONLINE * Zaptel * ONLINE * MySQL * ONLINE *
* SSH * ONLINE * Apache * ONLINE * Iptables * ONLINE *
* Fail2ban * ONLINE * IP Connect* ONLINE * Ip6tables * ONLINE *
* BlueTooth * ONLINE * Hidd * ONLINE * NTPD * ONLINE *
* Sendmail * ONLINE * Samba * OFFLINE * Webmin * ONLINE *
* Ethernet0 * ONLINE * Ethernet1 * N/A * Wlan0 * N/A *
********************************************************************
* Running Asterisk Version : Asterisk 1.4.21.2
* Asterisk Source Version : 1.4.21.2
* Zaptel Source Version : 1.4.12
* Libpri Source Version : 1.4.7
* Addons Source Version : 1.4.7
********************************************************************
newpbx.local on 192.168.2.31 - eth0
CentOS release 5.2 (Final) :32 Bit Kernel: 2.6.18-92.1.6.el5
********************************************************************
For help on PBX commands than you can run type help-pbx *
********************************************************************

Extensions set nat=yes

firewall settings===========

SOURCE / any
DESTINATION UnTrust Interface / MIP "external address" - HOST "internal address"
allowed services
name / protocol / source / destination
-------------------------------------------
Asterisk / TCP / 0-65535 / 5058-5062
Asterisk / UDP / 0-65535 / 5058-5062
Asterisk RTP / TCP / 0-65535 / 16384-32766
Asterisk RTP / UDP / 0-65535 / 16384-32766
SIP / TCP / 0-65535 / 5060
SIP / UDP / 0-65535 / 5060
SSH / TCP / 0-65535 / 22

sip.nat.conf =======

externip="external router MIP address"
localnet="lan subnet xxx.xxx.xxx.0"/255.255.255.0

I'l post the log next

Anything else that would help?

 

[FormosDerek]

Here is the log

call from 2123 to 124

log during connection============

[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [124@from-internal:1] Macro("SIP/2123-09aa0d20", "exten-vm|novm|124") in new stack
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-exten-vm:1] Macro("SIP/2123-09aa0d20", "user-callerid") in new stack
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:1] Set("SIP/2123-09aa0d20", "AMPUSER=2123") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/2123-09aa0d20", "0?report") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: GotoIf
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/2123-09aa0d20", "1|Set|REALCALLERIDNUM=2123") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: ExecIf
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:4] Set("SIP/2123-09aa0d20", "AMPUSER=2123") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:5] Set("SIP/2123-09aa0d20", "AMPUSERCIDNAME=Jim Robison") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/2123-09aa0d20", "0?report") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: GotoIf
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:7] Set("SIP/2123-09aa0d20", "AMPUSERCID=123") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:8] Set("SIP/2123-09aa0d20", "CALLERID(all)="Jim Robison" <123>") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:9] Set("SIP/2123-09aa0d20", "REALCALLERIDNUM=2123") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:10] ExecIf("SIP/2123-09aa0d20", "0|Set|CHANNEL(language)=") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: ExecIf
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:11] GotoIf("SIP/2123-09aa0d20", "0?continue") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: GotoIf
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:12] Set("SIP/2123-09aa0d20", "__TTL=64") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:13] GotoIf("SIP/2123-09aa0d20", "1?continue") in new stack
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Goto (macro-user-callerid,s,20)
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: GotoIf
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-user-callerid:20] NoOp("SIP/2123-09aa0d20", "Using CallerID "Jim Robison" <123>") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Noop
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Macro
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-exten-vm:2] Set("SIP/2123-09aa0d20", "RingGroupMethod=none") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-exten-vm:3] Set("SIP/2123-09aa0d20", "VMBOX=novm") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-exten-vm:4] Set("SIP/2123-09aa0d20", "EXTTOCALL=124") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] DEBUG[23121] func_db.c: DB: CFU/124 not found in database.
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-exten-vm:5] Set("SIP/2123-09aa0d20", "CFUEXT=") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] DEBUG[23121] func_db.c: DB: CFB/124 not found in database.
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-exten-vm:6] Set("SIP/2123-09aa0d20", "CFBEXT=") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-exten-vm:7] Set("SIP/2123-09aa0d20", "RT=""") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Set
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-exten-vm:8] Macro("SIP/2123-09aa0d20", "record-enable|124|IN") in new stack
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-record-enable:1] GotoIf("SIP/2123-09aa0d20", "1?check") in new stack
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Goto (macro-record-enable,s,4)
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: GotoIf
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-record-enable:4] AGI("SIP/2123-09aa0d20", "recordingcheck|20090122-085819|1232643499.306") in new stack
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
[Jan 22 08:58:19] VERBOSE[23121] logger.c: recordingcheck|20090122-085819|1232643499.306: Inbound recording not enabled

 

[FormosDerek]

Log part 2

[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- AGI Script recordingcheck completed, returning 0
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: AGI
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-record-enable:5] MacroExit("SIP/2123-09aa0d20", "") in new stack
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: Macro
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-exten-vm:9] Macro("SIP/2123-09aa0d20", "dial||tr|124") in new stack
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-dial:1] GotoIf("SIP/2123-09aa0d20", "1?dial") in new stack
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Goto (macro-dial,s,3)
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: GotoIf
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-dial:3] AGI("SIP/2123-09aa0d20", "dialparties.agi") in new stack
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[Jan 22 08:58:19] VERBOSE[23121] logger.c: dialparties.agi: Starting New Dialparties.agi
[Jan 22 08:58:19] VERBOSE[23124] logger.c: == Parsing '/etc/asterisk/manager.conf': [Jan 22 08:58:19] VERBOSE[23124] logger.c: Found
[Jan 22 08:58:19] VERBOSE[23124] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [Jan 22 08:58:19] VERBOSE[23124] logger.c: Found
[Jan 22 08:58:19] VERBOSE[23124] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Jan 22 08:58:19] VERBOSE[23124] logger.c: Found
[Jan 22 08:58:19] VERBOSE[23124] logger.c: == Manager 'admin' logged on from 127.0.0.1
[Jan 22 08:58:19] VERBOSE[23121] logger.c: dialparties.agi: Caller ID name is 'Jim Robison' number is '123'
[Jan 22 08:58:19] VERBOSE[23121] logger.c: dialparties.agi: Methodology of ring is 'none'
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- dialparties.agi: Added extension 124 to extension map
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- dialparties.agi: Extension 124 cf is disabled
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- dialparties.agi: Extension 124 do not disturb is disabled
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- dialparties.agi: dbset CALLTRACE/124 to 123
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- dialparties.agi: Filtered ARG3: 124
[Jan 22 08:58:19] VERBOSE[23124] logger.c: == Manager 'admin' logged off from 127.0.0.1
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- AGI Script dialparties.agi completed, returning 0
[Jan 22 08:58:19] DEBUG[23121] app_macro.c: Executed application: AGI
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Executing [s@macro-dial:7] Dial("SIP/2123-09aa0d20", "SIP/124||tr") in new stack
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- Called 124
[Jan 22 08:58:19] VERBOSE[23121] logger.c: -- SIP/124-09a9d170 is ringing
[Jan 22 08:58:20] VERBOSE[23121] logger.c: -- SIP/124-09a9d170 answered SIP/2123-09aa0d20
[Jan 22 08:58:25] VERBOSE[23121] logger.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/2123-09aa0d20' in macro 'dial'
[Jan 22 08:58:25] VERBOSE[23121] logger.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/2123-09aa0d20' in macro 'exten-vm'
[Jan 22 08:58:25] VERBOSE[23121] logger.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/2123-09aa0d20'
[Jan 22 08:58:25] VERBOSE[23121] logger.c: -- Executing [h@macro-dial:1] Macro("SIP/2123-09aa0d20", "hangupcall") in new stack
[Jan 22 08:58:25] VERBOSE[23121] logger.c: -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/2123-09aa0d20", "w") in new stack
[Jan 22 08:58:25] DEBUG[23121] app_macro.c: Executed application: ResetCDR
[Jan 22 08:58:25] VERBOSE[23121] logger.c: -- Executing [s@macro-hangupcall:2] NoCDR("SIP/2123-09aa0d20", "") in new stack
[Jan 22 08:58:25] DEBUG[23121] app_macro.c: Executed application: NoCDR
[Jan 22 08:58:25] VERBOSE[23121] logger.c: -- Executing [s@macro-hangupcall:3] GotoIf("SIP/2123-09aa0d20", "1?skiprg") in new stack
[Jan 22 08:58:25] VERBOSE[23121] logger.c: -- Goto (macro-hangupcall,s,6)
[Jan 22 08:58:25] DEBUG[23121] app_macro.c: Executed application: GotoIf
[Jan 22 08:58:25] VERBOSE[23121] logger.c: -- Executing [s@macro-hangupcall:6] GotoIf("SIP/2123-09aa0d20", "1?skipblkvm") in new stack
[Jan 22 08:58:25] VERBOSE[23121] logger.c: -- Goto (macro-hangupcall,s,9)
[Jan 22 08:58:25] DEBUG[23121] app_macro.c: Executed application: GotoIf
[Jan 22 08:58:25] VERBOSE[23121] logger.c: -- Executing [s@macro-hangupcall:9] GotoIf("SIP/2123-09aa0d20", "1?theend") in new stack
[Jan 22 08:58:25] VERBOSE[23121] logger.c: -- Goto (macro-hangupcall,s,11)
[Jan 22 08:58:25] DEBUG[23121] app_macro.c: Executed application: GotoIf
[Jan 22 08:58:25] VERBOSE[23121] logger.c: -- Executing [s@macro-hangupcall:11] Hangup("SIP/2123-09aa0d20", "") in new stack
[Jan 22 08:58:25] VERBOSE[23121] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/2123-09aa0d20' in macro 'hangupcall'
[Jan 22 08:58:25] VERBOSE[23121] logger.c: == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/2123-09aa0d20'
[Jan 22 08:58:30] VERBOSE[23125] logger.c: == Parsing '/etc/asterisk/manager.conf': [Jan 22 08:58:30] VERBOSE[23125] logger.c: Found
[Jan 22 08:58:30] VERBOSE[23125] logger.c: == Parsing '/etc/asterisk/manager_additional.conf': [Jan 22 08:58:30] VERBOSE[23125] logger.c: Found
[Jan 22 08:58:30] VERBOSE[23125] logger.c: == Parsing '/etc/asterisk/manager_custom.conf': [Jan 22 08:58:30] VERBOSE[23125] logger.c: Found
[Jan 22 08:58:30] VERBOSE[23125] logger.c: == Manager 'admin' logged on from 127.0.0.1

 

[Phone_User]

Turn on nat debug in the cli and verbosity at 5 and make a call from the remote phone. Post those logs, it should give more info.

 

[FormosDerek]

Fixed it. It was a port blocking issue in the company firewall.