Extensions not dialing Each other...Reporting Busy on the SIP Phone

Hi there, I was wondering if anyone could probably provide pointers to what my problem…I have asterisk 1.4.21.2 with Freepbx running on an Ubuntu machine (was installed using a package built from the Debpackage section of the freepbx website) Everything (Ques, inbound routes, IVR etc) works fine except dialing the extensions (phones)…basically the phones all come back with Busy. also i have voicemail setup for the phones but it does not redirect to voicemail as well…Attached is the console output

Verbosity is at least 4
– Executing [228@from-internal:1] Macro(“SIP/229-081e65b0”, “exten-vm|228|228”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“SIP/229-081e65b0”, “user-callerid”) in new stack
– Executing [s@macro-user-callerid:1] NoOp(“SIP/229-081e65b0”, “user-callerid: device 229”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/229-081e65b0”, “AMPUSER=229”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/229-081e65b0”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/229-081e65b0”, “1|Set|REALCALLERIDNUM=229”) in new stack
– Executing [s@macro-user-callerid:5] NoOp(“SIP/229-081e65b0”, “REALCALLERIDNUM is 229”) in new stack
– Executing [s@macro-user-callerid:6] Set(“SIP/229-081e65b0”, “AMPUSER=229”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/229-081e65b0”, “AMPUSERCIDNAME=Some User”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“SIP/229-081e65b0”, “0?report”) in new stack
– Executing [s@macro-user-callerid:9] Set(“SIP/229-081e65b0”, “AMPUSERCID=229”) in new stack
– Executing [s@macro-user-callerid:10] Set(“SIP/229-081e65b0”, “CALLERID(all)=“Some User” <229>”) in new stack
– Executing [s@macro-user-callerid:11] Set(“SIP/229-081e65b0”, “REALCALLERIDNUM=229”) in new stack
– Executing [s@macro-user-callerid:12] ExecIf(“SIP/229-081e65b0”, “0|Set|CHANNEL(language)=”) in new stack
– Executing [s@macro-user-callerid:13] NoOp(“SIP/229-081e65b0”, “TTL: ARG1: 228”) in new stack
– Executing [s@macro-user-callerid:14] GotoIf(“SIP/229-081e65b0”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:15] Set(“SIP/229-081e65b0”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:16] GotoIf(“SIP/229-081e65b0”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,23)
– Executing [s@macro-user-callerid:23] NoOp(“SIP/229-081e65b0”, “Using CallerID “Some User” <229>”) in new stack
– Executing [s@macro-exten-vm:2] Set(“SIP/229-081e65b0”, “FROMCONTEXT=exten-vm”) in new stack
– Executing [s@macro-exten-vm:3] Set(“SIP/229-081e65b0”, “VMBOX=228”) in new stack
– Executing [s@macro-exten-vm:4] Set(“SIP/229-081e65b0”, “EXTTOCALL=228”) in new stack
– Executing [s@macro-exten-vm:5] Set(“SIP/229-081e65b0”, “CFUEXT=”) in new stack
– Executing [s@macro-exten-vm:6] Set(“SIP/229-081e65b0”, “CFBEXT=”) in new stack
– Executing [s@macro-exten-vm:7] Set(“SIP/229-081e65b0”, “RT=15”) in new stack
– Executing [s@macro-exten-vm:8] Macro(“SIP/229-081e65b0”, “record-enable|228|IN”) in new stack
– Executing [s@macro-record-enable:1] GotoIf(“SIP/229-081e65b0”, “0?2:4”) in new stack
– Goto (macro-record-enable,s,4)
– Executing [s@macro-record-enable:4] AGI(“SIP/229-081e65b0”, “recordingcheck|20080925-115641|asterisk-1222340201.9”) in new stack
– Launched AGI Script /usr/share/asterisk/agi-bin/recordingcheck
recordingcheck|20080925-115641|asterisk-1222340201.9: Inbound recording not enabled
– AGI Script recordingcheck completed, returning 0
– Executing [s@macro-record-enable:5] NoOp(“SIP/229-081e65b0”, “No recording needed”) in new stack
– Executing [s@macro-exten-vm:9] Macro(“SIP/229-081e65b0”, “dial|15|tr|228”) in new stack
– Executing [s@macro-dial:1] GotoIf(“SIP/229-081e65b0”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [s@macro-dial:3] AGI(“SIP/229-081e65b0”, “dialparties.agi”) in new stack
– Launched AGI Script /usr/share/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
== Parsing ‘/etc/asterisk/manager.conf’: Found
== Parsing ‘/etc/asterisk/manager_additional.conf’: Found
== Parsing ‘/etc/asterisk/manager_custom.conf’: Found
== Parsing ‘/etc/asterisk/manager.d/*.conf’: Found
== Parsing ‘/etc/asterisk/manager.d/README.conf’: Found
== Parsing ‘/etc/asterisk/manager.d/freepbx.conf’: Found
== Manager ‘admin’ logged on from 127.0.0.1
dialparties.agi: Caller ID name is ‘Some User’ number is '229’
dialparties.agi: Methodology of ring is ‘none’
– dialparties.agi: Added extension 228 to extension map
– dialparties.agi: Extension 228 cf is disabled
– dialparties.agi: Extension 228 do not disturb is disabled
> dialparties.agi: extnum 228 has: cw: 0; hascfb: 0 [] hascfu: 0 []
> dialparties.agi: ExtensionState: 0
dialparties.agi: Extension 228 has ExtensionState: 0
– dialparties.agi: Checking CW and CFB status for extension 228
– dialparties.agi: dbset CALLTRACE/228 to 229
– dialparties.agi: Filtered ARG3: 228
[Sep 25 11:56:41] NOTICE[17515]: pbx.c:1892 pbx_extension_helper: No such label ‘normdial’ in extension ‘s’ in context ‘macro-dial’
== Manager ‘admin’ logged off from 127.0.0.1
– AGI Script dialparties.agi completed, returning 0
– Executing [s@macro-dial:4] NoOp(“SIP/229-081e65b0”, “Returned from dialparties with no extensions to call and DIALSTATUS: “) in new stack
– Executing [s@macro-exten-vm:10] Set(“SIP/229-081e65b0”, “SV_DIALSTATUS=”) in new stack
– Executing [s@macro-exten-vm:11] GosubIf(“SIP/229-081e65b0”, “0?docfu|1”) in new stack
– Executing [s@macro-exten-vm:12] GosubIf(“SIP/229-081e65b0”, “0?docfb|1”) in new stack
– Executing [s@macro-exten-vm:13] Set(“SIP/229-081e65b0”, “DIALSTATUS=”) in new stack
– Executing [s@macro-exten-vm:14] NoOp(“SIP/229-081e65b0”, “Voicemail is 228”) in new stack
– Executing [s@macro-exten-vm:15] GotoIf(“SIP/229-081e65b0”, “0?s-|1”) in new stack
– Executing [s@macro-exten-vm:16] NoOp(“SIP/229-081e65b0”, “Sending to Voicemail box 228”) in new stack
– Executing [s@macro-exten-vm:17] Macro(“SIP/229-081e65b0”, “vm|228|”) in new stack
– Executing [s@macro-vm:1] Macro(“SIP/229-081e65b0”, “user-callerid|SKIPTTL”) in new stack
– Executing [s@macro-user-callerid:1] NoOp(“SIP/229-081e65b0”, “user-callerid: Some User 229”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/229-081e65b0”, “AMPUSER=229”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/229-081e65b0”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/229-081e65b0”, “0|Set|REALCALLERIDNUM=229”) in new stack
– Executing [s@macro-user-callerid:5] NoOp(“SIP/229-081e65b0”, “REALCALLERIDNUM is 229”) in new stack
– Executing [s@macro-user-callerid:6] Set(“SIP/229-081e65b0”, “AMPUSER=229”) in new stack
– Executing [s@macro-user-callerid:7] Set(“SIP/229-081e65b0”, “AMPUSERCIDNAME=Some User”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“SIP/229-081e65b0”, “0?report”) in new stack
– Executing [s@macro-user-callerid:9] Set(“SIP/229-081e65b0”, “AMPUSERCID=229”) in new stack
– Executing [s@macro-user-callerid:10] Set(“SIP/229-081e65b0”, “CALLERID(all)=“Some User” <229>”) in new stack
– Executing [s@macro-user-callerid:11] Set(“SIP/229-081e65b0”, “REALCALLERIDNUM=229”) in new stack
– Executing [s@macro-user-callerid:12] ExecIf(“SIP/229-081e65b0”, “0|Set|CHANNEL(language)=”) in new stack
– Executing [s@macro-user-callerid:13] NoOp(“SIP/229-081e65b0”, “TTL: 64 ARG1: SKIPTTL”) in new stack
– Executing [s@macro-user-callerid:14] GotoIf(“SIP/229-081e65b0”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,23)
– Executing [s@macro-user-callerid:23] NoOp(“SIP/229-081e65b0”, “Using CallerID “Some User” <229>”) in new stack
– Executing [s@macro-vm:2] Set(“SIP/229-081e65b0”, “VMGAIN=”””) in new stack
[Sep 25 11:56:41] WARNING[17515]: func_db.c:58 function_db_read: DB requires an argument, DB(/)
– Executing [s@macro-vm:3] GotoIf(“SIP/229-081e65b0”, “1?vmx|1”) in new stack
– Goto (macro-vm,vmx,1)
– Executing [vmx@macro-vm:1] GotoIf(“SIP/229-081e65b0”, “0?s-|1”) in new stack
– Executing [vmx@macro-vm:2] Set(“SIP/229-081e65b0”, “MODE=unavail”) in new stack
– Executing [vmx@macro-vm:3] GotoIf(“SIP/229-081e65b0”, “1?notdirect”) in new stack
– Goto (macro-vm,vmx,5)
– Executing [vmx@macro-vm:5] NoOp(“SIP/229-081e65b0”, “Checking if ext 228 is enabled: enabled”) in new stack
– Executing [vmx@macro-vm:6] GotoIf(“SIP/229-081e65b0”, “0?s-|1”) in new stack
– Executing [vmx@macro-vm:7] Macro(“SIP/229-081e65b0”, “get-vmcontext|228”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“SIP/229-081e65b0”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/229-081e65b0”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“SIP/229-081e65b0”, “”) in new stack
– Executing [vmx@macro-vm:8] AGI(“SIP/229-081e65b0”, “checksound.agi|/var/spool/asterisk/voicemail/default/228/unavail”) in new stack
– Launched AGI Script /usr/share/asterisk/agi-bin/checksound.agi
checksound.agi|/var/spool/asterisk/voicemail/default/228/unavail: VmX requires: /var/spool/asterisk/voicemail/default/228/unavail.wav or .WAV exist in order to function
– AGI Script checksound.agi completed, returning 0
– Executing [vmx@macro-vm:9] GotoIf(“SIP/229-081e65b0”, “1?nofile”) in new stack
– Goto (macro-vm,vmx,61)
– Executing [vmx@macro-vm:61] NoOp(“SIP/229-081e65b0”, “File for mode: unavail does not exist| SYSTEMSTATUS: APPERROR| going to normal voicemail”) in new stack
– Executing [vmx@macro-vm:62] Goto(“SIP/229-081e65b0”, “s-|1”) in new stack
– Goto (macro-vm,s-,1)
– Executing [228@from-internal:2] Hangup(“SIP/229-081e65b0”, “”) in new stack
== Spawn extension (from-internal, 228, 2) exited non-zero on ‘SIP/229-081e65b0’
– Executing [h@from-internal:1] Macro(“SIP/229-081e65b0”, “hangupcall”) in new stack
– Executing [s@macro-hangupcall:1] ResetCDR(“SIP/229-081e65b0”, “w”) in new stack
– Executing [s@macro-hangupcall:2] NoCDR(“SIP/229-081e65b0”, “”) in new stack
– Executing [s@macro-hangupcall:3] GotoIf(“SIP/229-081e65b0”, “1?skiprg”) in new stack
– Goto (macro-hangupcall,s,6)
– Executing [s@macro-hangupcall:6] GotoIf(“SIP/229-081e65b0”, “1?skipblkvm”) in new stack
– Goto (macro-hangupcall,s,9)
– Executing [s@macro-hangupcall:9] GotoIf(“SIP/229-081e65b0”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,11)
– Executing [s@macro-hangupcall:11] Hangup(“SIP/229-081e65b0”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on ‘SIP/229-081e65b0’ in macro ‘hangupcall’
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on ‘SIP/229-081e65b0’

Will appreciate any help…cheers!!

You have not stated what version of FreePBX you are running. The error you are getting is this:
[Sep 25 11:56:41] NOTICE[17515]: pbx.c:1892 pbx_extension_helper: No such label ‘normdial’ in extension ‘s’ in context ‘macro-dial’

And that error is probably that your install of FreePBX is corrupt. If you look at the files in /etc/asterisk there should be some files that are symlinked, like extensions.conf. If that file is NOT symlinked then that will cause odd behavior.

I suggest that you download and install the latest FreePBX version 2.5 from http://www.freepbx.org/download-freepbx and that you read the upgrade instructions at http://www.freepbx.org/support/documentation/installation/upgrading-your-system

Mikael

quite an easy fix, it appears some distribution of FreePBX had an error.

vi /usr/share/asterisk/agi-bin/dialparties.agi

go to line 620 and change:
$AGI->set_priority(“dialparties”); // dial command was at priority 10

to:
$AGI->set_priority(“10”); // dial command was at priority 10

Done!

You are WRONG!!!

You must have a corrupt setup as the

$AGI->set_priority("dialparties"); // dial command was at priority 10
is what it should be and have been that way since revision 5341, changed 12/04/07 12:06:52 (11 months ago)

Look here when it was changed: http://www.freepbx.org/trac/changeset/5341

Once again, you have a corrupt install. What version of FreePBX are you running?

I have the same problem after installing from this description:

http://www.freepbx.org/trac/wiki/DebPackages

wich created package release 2.5.1~dfsg-1