Can't Call other Extension but can check Voicemail?

What I am using:

1 xlite phone EXTENSION 102
1 polycom IP 650 EXTENSION 101
1 asterisk box

I have everything configured in place. Either extension will work fine on xlite. The scenario is I can call 101 from 102 without a problem. It rings and when picked up I can talk. But if 101 calls 102 it sits and then gives short beep noises. The weird thing is from 101 I am able to dial voicemail and it connects (*97).

I have done sip debugging and the following will be below. I can’t figure it out and it is driving me nuts. Could it be something with the 650’s digitmap?

Here is the debug:

<------------>
server*CLI> 
<--- Transmitting (no NAT) to 10.0.7.110:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.7.110;branch=z9hG4bKcdd6cf1247F11FB3;received=10.0.7.110
From: "Bosch" <sip:101@10.0.7.125>;tag=7AE65055-679E6596
To: <sip:101@10.0.7.125>;tag=as3881afba
Call-ID: ac3ad1d2-36be0a73-edb49df4@10.0.7.110
CSeq: 16 REGISTER
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Expires: 120
Contact: <sip:101@10.0.7.110>;expires=120
Date: Thu, 19 Mar 2009 00:05:06 GMT
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog 'ac3ad1d2-36be0a73-edb49df4@10.0.7.110' in 32000 ms (Method: REGISTER)
    -- Executing [*102@from-internal:5] Wait("SIP/101-091df390", "1") in new stack
server*CLI> 
<--- SIP read from 10.0.7.110:5060 --->
SUBSCRIBE sip:Unknown@10.0.7.125 SIP/2.0
Via: SIP/2.0/UDP 10.0.7.110;branch=z9hG4bKae03cb3484369595
From: "Bosch" <sip:101@10.0.7.125>;tag=74247B83-867CDD04
To: "Unknown" <sip:Unknown@10.0.7.125>;tag=as18749d6b
CSeq: 9 SUBSCRIBE
Call-ID: cc36840-4d224521-db5fb4e2@10.0.7.110
Contact: <sip:101@10.0.7.110>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER
Event: message-summary
User-Agent: PolycomSoundPointIP-SPIP_650-UA/3.0.3.0401
Accept: application/simple-message-summary
Authorization: Digest username="101", realm="asterisk", nonce="4e720a09", uri="sip:224@10.0.7.125:5060", response="640f0c9423b690b0a71852dffa71c3bb", algorithm=MD5
Max-Forwards: 70
Expires: 120
Content-Length: 0


<------------->
--- (15 headers 0 lines) ---
Found peer '101'
Looking for 224 in from-internal (domain 10.0.7.125)
Scheduling destruction of SIP dialog 'cc36840-4d224521-db5fb4e2@10.0.7.110' in 130000 ms (Method: SUBSCRIBE)

<--- Transmitting (no NAT) to 10.0.7.110:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.7.110;branch=z9hG4bKae03cb3484369595;received=10.0.7.110
From: "Bosch" <sip:101@10.0.7.125>;tag=74247B83-867CDD04
To: "Unknown" <sip:Unknown@10.0.7.125>;tag=as18749d6b
Call-ID: cc36840-4d224521-db5fb4e2@10.0.7.110
CSeq: 9 SUBSCRIBE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Expires: 120
Contact: <sip:Unknown@10.0.7.125>;expires=120
Content-Length: 0


<------------>
Reliably Transmitting (no NAT) to 10.0.7.110:5060:
NOTIFY sip:101@10.0.7.110 SIP/2.0
Via: SIP/2.0/UDP 10.0.7.125:5060;branch=z9hG4bK7416b609;rport
From: "Unknown" <sip:Unknown@10.0.7.125>;tag=as18749d6b
To: <sip:sip:101@10.0.7.110>;tag=74247B83-867CDD04
Contact: <sip:Unknown@10.0.7.125>
Call-ID: cc36840-4d224521-db5fb4e2@10.0.7.110
CSeq: 108 NOTIFY
User-Agent: Asterisk PBX
Max-Forwards: 70
Event: message-summary
Content-Type: application/simple-message-summary
Subscription-State: active
Content-Length: 90

Messages-Waiting: no
Message-Account: sip:asterisk@10.0.7.125
Voice-Message: 0/0 (0/0)

---
server*CLI> 
<--- SIP read from 10.0.7.110:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.0.7.125:5060;branch=z9hG4bK7416b609;rport
From: "Unknown" <sip:Unknown@10.0.7.125>;tag=as18749d6b
To: <sip:sip:101@10.0.7.110>;tag=74247B83-867CDD04
CSeq: 108 NOTIFY
Call-ID: cc36840-4d224521-db5fb4e2@10.0.7.110
Contact: <sip:101@10.0.7.110>
Event: message-summary
User-Agent: PolycomSoundPointIP-SPIP_650-UA/3.0.3.0401
Content-Length: 0


<------------->
--- (10 headers 0 lines) ---
    -- Executing [*102@from-internal:6] Congestion("SIP/101-091df390", "20") in new stack

<--- Transmitting (no NAT) to 10.0.7.110:5060 --->
SIP/2.0 503 Service Unavailable
Via: SIP/2.0/UDP 10.0.7.110;branch=z9hG4bK7f85c30d980494E;received=10.0.7.110
From: "Bosch" <sip:101@10.0.7.125>;tag=D50EC24-593DA885
To: <sip:*102@10.0.7.125;user=phone>;tag=as5be76adc
Call-ID: c8ee6041-c35f4c02-222c0ea3@10.0.7.110
CSeq: 3 INVITE
User-Agent: Asterisk PBX
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY
Supported: replaces
Contact: <sip:*102@10.0.7.125>
Content-Length: 0


<------------>
  == Spawn extension (from-internal, *102, 6) exited non-zero on 'SIP/101-091df390'
    -- Executing [h@from-internal:1] Macro("SIP/101-091df390", "hangupcall") in new stack
    -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/101-091df390", "w") in new stack
    -- Executing [s@macro-hangupcall:2] NoCDR("SIP/101-091df390", "") in new stack
    -- Executing [s@macro-hangupcall:3] GotoIf("SIP/101-091df390", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,6)
    -- Executing [s@macro-hangupcall:6] GotoIf("SIP/101-091df390", "1?skipblkvm") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing [s@macro-hangupcall:9] GotoIf("SIP/101-091df390", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,11)
    -- Executing [s@macro-hangupcall:11] Hangup("SIP/101-091df390", "") in new stack
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/101-091df390' in macro 'hangupcall'
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/101-091df390'
server*CLI> 
<--- SIP read from 10.0.7.110:5060 --->
ACK sip:*102@10.0.7.125 SIP/2.0
Via: SIP/2.0/UDP 10.0.7.110;branch=z9hG4bK7f85c30d980494E
From: "Bosch" <sip:101@10.0.7.125>;tag=D50EC24-593DA885
To: <sip:*102@10.0.7.125;user=phone>;tag=as5be76adc
CSeq: 3 ACK
Call-ID: c8ee6041-c35f4c02-222c0ea3@10.0.7.110
Contact: <sip:101@10.0.7.110>
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, INFO, MESSAGE, SUBSCRIBE, NOTIFY, PRACK, UPDATE, REFER
User-Agent: PolycomSoundPointIP-SPIP_650-UA/3.0.3.0401
Max-Forwards: 70
Content-Length: 0


<------------->
--- (11 headers 0 lines) ---
Really destroying SIP dialog 'c8ee6041-c35f4c02-222c0ea3@10.0.7.110' Method: ACK

<--- SIP read from 10.0.7.114:29134 --->



<------------->
server*CLI> sip set debug off
SIP Debugging Disabled
    -- Executing [101@from-internal:1] Macro("SIP/102-091df390", "exten-vm|novm|101") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("SIP/102-091df390", "user-callerid") in new stack
    -- Executing [s@macro-user-callerid:1] NoOp("SIP/102-091df390", "user-callerid: device 102") in new stack
    -- Executing [s@macro-user-callerid:2] Set("SIP/102-091df390", "AMPUSER=102") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("SIP/102-091df390", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] GotoIf("SIP/102-091df390", "0?start") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/102-091df390", "REALCALLERIDNUM=102") in new stack
    -- Executing [s@macro-user-callerid:6] NoOp("SIP/102-091df390", "REALCALLERIDNUM is 102") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/102-091df390", "AMPUSER=102") in new stack
    -- Executing [s@macro-user-callerid:8] Set("SIP/102-091df390", "AMPUSERCIDNAME=About Us") in new stack
    -- Executing [s@macro-user-callerid:9] GotoIf("SIP/102-091df390", "0?report") in new stack
    -- Executing [s@macro-user-callerid:10] Set("SIP/102-091df390", "AMPUSERCID=102") in new stack
    -- Executing [s@macro-user-callerid:11] Set("SIP/102-091df390", "CALLERID(all)="About Us" <102>") in new stack
    -- Executing [s@macro-user-callerid:12] Set("SIP/102-091df390", "REALCALLERIDNUM=102") in new stack
    -- Executing [s@macro-user-callerid:13] NoOp("SIP/102-091df390", "TTL:  ARG1: novm") in new stack
    -- Executing [s@macro-user-callerid:14] GotoIf("SIP/102-091df390", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:15] Set("SIP/102-091df390", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("SIP/102-091df390", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,23)
    -- Executing [s@macro-user-callerid:23] NoOp("SIP/102-091df390", "Using CallerID "About Us" <102>") in new stack
    -- Executing [s@macro-exten-vm:2] Set("SIP/102-091df390", "FROMCONTEXT=exten-vm") in new stack
    -- Executing [s@macro-exten-vm:3] Set("SIP/102-091df390", "VMBOX=novm") in new stack
    -- Executing [s@macro-exten-vm:4] Set("SIP/102-091df390", "EXTTOCALL=101") in new stack
    -- Executing [s@macro-exten-vm:5] Set("SIP/102-091df390", "CFUEXT=") in new stack
    -- Executing [s@macro-exten-vm:6] Set("SIP/102-091df390", "CFBEXT=") in new stack
    -- Executing [s@macro-exten-vm:7] Set("SIP/102-091df390", "RT=""") in new stack
    -- Executing [s@macro-exten-vm:8] Macro("SIP/102-091df390", "record-enable|101|IN") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("SIP/102-091df390", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] AGI("SIP/102-091df390", "recordingcheck|20090318-190626|1237421186.28") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
  recordingcheck|20090318-190626|1237421186.28: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing [s@macro-record-enable:5] NoOp("SIP/102-091df390", "No recording needed") in new stack
    -- Executing [s@macro-exten-vm:9] Macro("SIP/102-091df390", "dial||tr|101") in new stack
    -- Executing [s@macro-dial:1] GotoIf("SIP/102-091df390", "1?dial") in new stack
    -- Goto (macro-dial,s,3)
    -- Executing [s@macro-dial:3] AGI("SIP/102-091df390", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  dialparties.agi: Starting New Dialparties.agi
  == Parsing '/etc/asterisk/manager.conf': Found
  == Manager 'admin' logged on from 127.0.0.1
  dialparties.agi: Caller ID name is 'About Us' number is '102'
  dialparties.agi: Methodology of ring is  'none'
    --  dialparties.agi: Added extension 101 to extension map
    --  dialparties.agi: Extension 101 cf is disabled
    --  dialparties.agi: Extension 101 do not disturb is disabled
    --  dialparties.agi: dbset CALLTRACE/101 to 102
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing [s@macro-dial:10] Dial("SIP/102-091df390", "SIP/101||tr") in new stack
    -- Called 101
  == Manager 'admin' logged off from 127.0.0.1
    -- SIP/101-091e2838 is ringing
    -- SIP/101-091e2838 answered SIP/102-091df390
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/102-091df390' in macro 'dial'
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/102-091df390' in macro 'exten-vm'
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/102-091df390'
    -- Executing [h@macro-dial:1] Macro("SIP/102-091df390", "hangupcall") in new stack
    -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/102-091df390", "w") in new stack
    -- Executing [s@macro-hangupcall:2] NoCDR("SIP/102-091df390", "") in new stack
    -- Executing [s@macro-hangupcall:3] GotoIf("SIP/102-091df390", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,6)
    -- Executing [s@macro-hangupcall:6] GotoIf("SIP/102-091df390", "1?skipblkvm") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing [s@macro-hangupcall:9] GotoIf("SIP/102-091df390", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,11)
    -- Executing [s@macro-hangupcall:11] Hangup("SIP/102-091df390", "") in new stack
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/102-091df390' in macro 'hangupcall'
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/102-091df390'
    -- Executing [*102@from-internal:1] ResetCDR("SIP/101-091e8480", "") in new stack
    -- Executing [*102@from-internal:2] NoCDR("SIP/101-091e8480", "") in new stack
    -- Executing [*102@from-internal:3] Wait("SIP/101-091e8480", "1") in new stack
    -- Executing [*102@from-internal:4] Playback("SIP/101-091e8480", "silence/1&feature-not-avail-line&silence/1&cannot-complete-as-dialed&check-number-dial-again|noanswer") in new stack
    -- <SIP/101-091e8480> Playing 'silence/1' (language 'en')
    -- <SIP/101-091e8480> Playing 'silence/1' (language 'en')
    -- Executing [*102@from-internal:5] Wait("SIP/101-091e8480", "1") in new stack
    -- Executing [*102@from-internal:6] Congestion("SIP/101-091e8480", "20") in new stack
  == Spawn extension (from-internal, *102, 6) exited non-zero on 'SIP/101-091e8480'
    -- Executing [h@from-internal:1] Macro("SIP/101-091e8480", "hangupcall") in new stack
    -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/101-091e8480", "w") in new stack
    -- Executing [s@macro-hangupcall:2] NoCDR("SIP/101-091e8480", "") in new stack
    -- Executing [s@macro-hangupcall:3] GotoIf("SIP/101-091e8480", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,6)
    -- Executing [s@macro-hangupcall:6] GotoIf("SIP/101-091e8480", "1?skipblkvm") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing [s@macro-hangupcall:9] GotoIf("SIP/101-091e8480", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,11)
    -- Executing [s@macro-hangupcall:11] Hangup("SIP/101-091e8480", "") in new stack
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/101-091e8480' in macro 'hangupcall'
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/101-091e8480'
    -- Executing [101@from-internal:1] Macro("SIP/102-091df390", "exten-vm|novm|101") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("SIP/102-091df390", "user-callerid") in new stack
    -- Executing [s@macro-user-callerid:1] NoOp("SIP/102-091df390", "user-callerid: device 102") in new stack
    -- Executing [s@macro-user-callerid:2] Set("SIP/102-091df390", "AMPUSER=102") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("SIP/102-091df390", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] GotoIf("SIP/102-091df390", "0?start") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/102-091df390", "REALCALLERIDNUM=102") in new stack
    -- Executing [s@macro-user-callerid:6] NoOp("SIP/102-091df390", "REALCALLERIDNUM is 102") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/102-091df390", "AMPUSER=102") in new stack
    -- Executing [s@macro-user-callerid:8] Set("SIP/102-091df390", "AMPUSERCIDNAME=About Us") in new stack
    -- Executing [s@macro-user-callerid:9] GotoIf("SIP/102-091df390", "0?report") in new stack
    -- Executing [s@macro-user-callerid:10] Set("SIP/102-091df390", "AMPUSERCID=102") in new stack
    -- Executing [s@macro-user-callerid:11] Set("SIP/102-091df390", "CALLERID(all)="About Us" <102>") in new stack
    -- Executing [s@macro-user-callerid:12] Set("SIP/102-091df390", "REALCALLERIDNUM=102") in new stack
    -- Executing [s@macro-user-callerid:13] NoOp("SIP/102-091df390", "TTL:  ARG1: novm") in new stack
    -- Executing [s@macro-user-callerid:14] GotoIf("SIP/102-091df390", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:15] Set("SIP/102-091df390", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("SIP/102-091df390", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,23)
    -- Executing [s@macro-user-callerid:23] NoOp("SIP/102-091df390", "Using CallerID "About Us" <102>") in new stack
    -- Executing [s@macro-exten-vm:2] Set("SIP/102-091df390", "FROMCONTEXT=exten-vm") in new stack
    -- Executing [s@macro-exten-vm:3] Set("SIP/102-091df390", "VMBOX=novm") in new stack
    -- Executing [s@macro-exten-vm:4] Set("SIP/102-091df390", "EXTTOCALL=101") in new stack
    -- Executing [s@macro-exten-vm:5] Set("SIP/102-091df390", "CFUEXT=") in new stack
    -- Executing [s@macro-exten-vm:6] Set("SIP/102-091df390", "CFBEXT=") in new stack
    -- Executing [s@macro-exten-vm:7] Set("SIP/102-091df390", "RT=""") in new stack
    -- Executing [s@macro-exten-vm:8] Macro("SIP/102-091df390", "record-enable|101|IN") in new stack
    -- Executing [s@macro-record-enable:1] GotoIf("SIP/102-091df390", "0?2:4") in new stack
    -- Goto (macro-record-enable,s,4)
    -- Executing [s@macro-record-enable:4] AGI("SIP/102-091df390", "recordingcheck|20090318-190654|1237421214.31") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
  recordingcheck|20090318-190654|1237421214.31: Inbound recording not enabled
    -- AGI Script recordingcheck completed, returning 0
    -- Executing [s@macro-record-enable:5] NoOp("SIP/102-091df390", "No recording needed") in new stack
    -- Executing [s@macro-exten-vm:9] Macro("SIP/102-091df390", "dial||tr|101") in new stack
    -- Executing [s@macro-dial:1] GotoIf("SIP/102-091df390", "1?dial") in new stack
    -- Goto (macro-dial,s,3)
    -- Executing [s@macro-dial:3] AGI("SIP/102-091df390", "dialparties.agi") in new stack
    -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
  dialparties.agi: Starting New Dialparties.agi
  == Parsing '/etc/asterisk/manager.conf': Found
  == Manager 'admin' logged on from 127.0.0.1
  dialparties.agi: Caller ID name is 'About Us' number is '102'
  dialparties.agi: Methodology of ring is  'none'
    --  dialparties.agi: Added extension 101 to extension map
    --  dialparties.agi: Extension 101 cf is disabled
    --  dialparties.agi: Extension 101 do not disturb is disabled
    --  dialparties.agi: dbset CALLTRACE/101 to 102
  == Manager 'admin' logged off from 127.0.0.1
    -- AGI Script dialparties.agi completed, returning 0
    -- Executing [s@macro-dial:10] Dial("SIP/102-091df390", "SIP/101||tr") in new stack
    -- Called 101
    -- SIP/101-091e2838 is ringing
    -- SIP/101-091e2838 answered SIP/102-091df390
  == Parsing '/etc/asterisk/manager.conf': Found
  == Manager 'admin' logged on from 127.0.0.1
  == Manager 'admin' logged off from 127.0.0.1
  == Parsing '/etc/asterisk/manager.conf': Found
  == Manager 'admin' logged on from 127.0.0.1
  == Manager 'admin' logged off from 127.0.0.1
    -- Started music on hold, class 'default', on SIP/101-091e2838
    -- Stopped music on hold on SIP/101-091e2838
    -- Started music on hold, class 'default', on SIP/101-091e2838
    -- Stopped music on hold on SIP/101-091e2838
    -- Started music on hold, class 'default', on SIP/102-091df390
    -- Executing [10@from-internal:1] ResetCDR("SIP/101-091f21c0", "") in new stack
    -- Executing [10@from-internal:2] NoCDR("SIP/101-091f21c0", "") in new stack
    -- Executing [10@from-internal:3] Wait("SIP/101-091f21c0", "1") in new stack
    -- Executing [10@from-internal:4] Playback("SIP/101-091f21c0", "silence/1&cannot-complete-as-dialed&check-number-dial-again|noanswer") in new stack
    -- <SIP/101-091f21c0> Playing 'silence/1' (language 'en')
    -- Executing [10@from-internal:5] Wait("SIP/101-091f21c0", "1") in new stack
    -- Executing [10@from-internal:6] Congestion("SIP/101-091f21c0", "20") in new stack
  == Spawn extension (from-internal, 10, 6) exited non-zero on 'SIP/101-091f21c0'
    -- Executing [h@from-internal:1] Macro("SIP/101-091f21c0", "hangupcall") in new stack
    -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/101-091f21c0", "w") in new stack
    -- Executing [s@macro-hangupcall:2] NoCDR("SIP/101-091f21c0", "") in new stack
    -- Executing [s@macro-hangupcall:3] GotoIf("SIP/101-091f21c0", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,6)
    -- Executing [s@macro-hangupcall:6] GotoIf("SIP/101-091f21c0", "1?skipblkvm") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing [s@macro-hangupcall:9] GotoIf("SIP/101-091f21c0", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,11)
    -- Executing [s@macro-hangupcall:11] Hangup("SIP/101-091f21c0", "") in new stack
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/101-091f21c0' in macro 'hangupcall'
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/101-091f21c0'
    -- Stopped music on hold on SIP/102-091df390
    -- Started music on hold, class 'default', on SIP/102-091df390
    -- Executing [*102@from-internal:1] ResetCDR("SIP/101-091fc308", "") in new stack
    -- Executing [*102@from-internal:2] NoCDR("SIP/101-091fc308", "") in new stack
    -- Executing [*102@from-internal:3] Wait("SIP/101-091fc308", "1") in new stack
    -- Executing [*102@from-internal:4] Playback("SIP/101-091fc308", "silence/1&feature-not-avail-line&silence/1&cannot-complete-as-dialed&check-number-dial-again|noanswer") in new stack
    -- <SIP/101-091fc308> Playing 'silence/1' (language 'en')
    -- <SIP/101-091fc308> Playing 'silence/1' (language 'en')
    -- Executing [*102@from-internal:5] Wait("SIP/101-091fc308", "1") in new stack
    -- Executing [*102@from-internal:6] Congestion("SIP/101-091fc308", "20") in new stack
  == Spawn extension (from-internal, *102, 6) exited non-zero on 'SIP/101-091fc308'
    -- Executing [h@from-internal:1] Macro("SIP/101-091fc308", "hangupcall") in new stack
    -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/101-091fc308", "w") in new stack
    -- Executing [s@macro-hangupcall:2] NoCDR("SIP/101-091fc308", "") in new stack
    -- Executing [s@macro-hangupcall:3] GotoIf("SIP/101-091fc308", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,6)
    -- Executing [s@macro-hangupcall:6] GotoIf("SIP/101-091fc308", "1?skipblkvm") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing [s@macro-hangupcall:9] GotoIf("SIP/101-091fc308", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,11)
    -- Executing [s@macro-hangupcall:11] Hangup("SIP/101-091fc308", "") in new stack
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/101-091fc308' in macro 'hangupcall'
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/101-091fc308'
    -- Stopped music on hold on SIP/102-091df390
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/102-091df390' in macro 'dial'
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/102-091df390' in macro 'exten-vm'
  == Spawn extension (macro-dial, s, 10) exited non-zero on 'SIP/102-091df390'
    -- Executing [h@macro-dial:1] Macro("SIP/102-091df390", "hangupcall") in new stack
    -- Executing [s@macro-hangupcall:1] ResetCDR("SIP/102-091df390", "w") in new stack
    -- Executing [s@macro-hangupcall:2] NoCDR("SIP/102-091df390", "") in new stack
    -- Executing [s@macro-hangupcall:3] GotoIf("SIP/102-091df390", "1?skiprg") in new stack
    -- Goto (macro-hangupcall,s,6)
    -- Executing [s@macro-hangupcall:6] GotoIf("SIP/102-091df390", "1?skipblkvm") in new stack
    -- Goto (macro-hangupcall,s,9)
    -- Executing [s@macro-hangupcall:9] GotoIf("SIP/102-091df390", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,11)
    -- Executing [s@macro-hangupcall:11] Hangup("SIP/102-091df390", "") in new stack
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/102-091df390' in macro 'hangupcall'
  == Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'SIP/102-091df390'
server*CLI> sip set debug off

Any help would be great and I would appreciate it in advance.

Thanks,
JW

The trace is missing steps (priorities) 1 to 4 of the dialplan. Step 6 would explain the failure with the tones you describe.

Either steps 1 to 4 don’t include Dial, or they do, but it has failed.