Phone won't work

I have a trixbox pbx with asterisk under it. This pbx provides phone services to several head start classrooms. Starting at the end of last school year one phone stopped accepting incoming calls. I have replaced the phone, deleted and re-entered the extension, gave it a new extension, replaced all the networking parts, moved the phone, done just about everything I can think of yet this one phone will not accept incoming calls. I moved the phone to a different classroom and the issue follows the phone. I put in a new phone and the issue follows the particular phone or network location or something. The phone is not on do not disturb, not forwarded. I captured the CLI output and have included it below. If anyone can tell me why this particular location is having issues I will be most grateful!

Here is the cli output:

– Executing [8145639226@from-sip-external:1] NoOp(“SIP/Citynet-00002da8”, “Received incoming SIP connection from unknown peer to 8145639226”) in new stack
– Executing [8145639226@from-sip-external:2] Set(“SIP/Citynet-00002da8”, “DID=814xxxxxxx”) in new stack
– Executing [8145639226@from-sip-external:3] Goto(“SIP/Citynet-00002da8”, “s,1”) in new stack
– Goto (from-sip-external,s,1)
– Executing [s@from-sip-external:1] GotoIf(“SIP/Citynet-00002da8”, “1?from-trunk,814xxxxxxx,1”) in new stack
– Goto (from-trunk,8145639226,1)
– Executing [8145639226@from-trunk:1] Set(“SIP/Citynet-00002da8”, “__FROM_DID=814xxxxxxx”) in new stack
– Executing [8145639226@from-trunk:2] Gosub(“SIP/Citynet-00002da8”, “app-blacklist-check,s,1”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/Citynet-00002da8”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Return(“SIP/Citynet-00002da8”, “”) in new stack
– Executing [8145639226@from-trunk:3] ExecIf(“SIP/Citynet-00002da8”, “1 ?Set(CALLERID(name)=814xxxxxxx)”) in new stack
– Executing [8145639226@from-trunk:4] Set(“SIP/Citynet-00002da8”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [8145639226@from-trunk:5] Set(“SIP/Citynet-00002da8”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [8145639226@from-trunk:6] Goto(“SIP/Citynet-00002da8”, “from-did-direct,4102,1”) in new stack
– Goto (from-did-direct,4102,1)
– Executing [4102@from-did-direct:1] Macro(“SIP/Citynet-00002da8”, “exten-vm,4102,4102”) in new stack
– Executing [s@macro-exten-vm:1] Macro(“SIP/Citynet-00002da8”, “user-callerid”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/Citynet-00002da8”, “AMPUSER=814xxxxxxx”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“SIP/Citynet-00002da8”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“SIP/Citynet-00002da8”, “1?Set(REALCALLERIDNUM=814xxxxxxx)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“SIP/Citynet-00002da8”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/Citynet-00002da8”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/Citynet-00002da8”, “1?report”) in new stack
– Goto (macro-user-callerid,s,10)
– Executing [s@macro-user-callerid:10] GotoIf(“SIP/Citynet-00002da8”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:11] Set(“SIP/Citynet-00002da8”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:12] GotoIf(“SIP/Citynet-00002da8”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,19)
– Executing [s@macro-user-callerid:19] NoOp(“SIP/Citynet-00002da8”, “Using CallerID “814xxxxxxx” <814xxxxxxx>”) in new stack
– Executing [s@macro-exten-vm:2] Set(“SIP/Citynet-00002da8”, “RingGroupMethod=none”) in new stack
– Executing [s@macro-exten-vm:3] Set(“SIP/Citynet-00002da8”, “VMBOX=4102”) in new stack
– Executing [s@macro-exten-vm:4] Set(“SIP/Citynet-00002da8”, “EXTTOCALL=4102”) in new stack
– Executing [s@macro-exten-vm:5] Set(“SIP/Citynet-00002da8”, “CFUEXT=”) in new stack
– Executing [s@macro-exten-vm:6] Set(“SIP/Citynet-00002da8”, “CFBEXT=”) in new stack
– Executing [s@macro-exten-vm:7] Set(“SIP/Citynet-00002da8”, “RT=30”) in new stack
– Executing [s@macro-exten-vm:8] Macro(“SIP/Citynet-00002da8”, “record-enable,4102,IN”) in new stack
– Executing [s@macro-record-enable:1] GotoIf(“SIP/Citynet-00002da8”, “1?check”) in new stack
– Goto (macro-record-enable,s,4)
– Executing [s@macro-record-enable:4] AGI(“SIP/Citynet-00002da8”, “recordingcheck,20120911-094143,1347370903.11735”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/recordingcheck
recordingcheck,20120911-094143,1347370903.11735: Inbound recording not enabled
– <SIP/Citynet-00002da8>AGI Script recordingcheck completed, returning 0
– Executing [s@macro-record-enable:5] MacroExit(“SIP/Citynet-00002da8”, “”) in new stack
– Executing [s@macro-exten-vm:9] Macro(“SIP/Citynet-00002da8”, “dial,30,tr,4102”) in new stack
– Executing [s@macro-dial:1] GotoIf(“SIP/Citynet-00002da8”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [s@macro-dial:3] AGI(“SIP/Citynet-00002da8”, “dialparties.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is ‘814xxxxxxx’ number is '814xxxxxxx’
dialparties.agi: Methodology of ring is ‘none’
– dialparties.agi: Added extension 4102 to extension map
– dialparties.agi: Extension 4102 cf is disabled
– dialparties.agi: Extension 4102 do not disturb is disabled
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
– dialparties.agi: dbset CALLTRACE/4102 to 814xxxxxxx
– dialparties.agi: Filtered ARG3: 4102
– <SIP/Citynet-00002da8>AGI Script dialparties.agi completed, returning 0
– Executing [s@macro-dial:7] Dial(“SIP/Citynet-00002da8”, “SIP/4102,30,tr”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
== Using SIP VRTP TOS bits 136
== Using SIP VRTP CoS mark 6
== Everyone is busy/congested at this time (1:0/0/1)
– Executing [s@macro-dial:8] Set(“SIP/Citynet-00002da8”, “DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-dial:9] GosubIf(“SIP/Citynet-00002da8”, “0?CHANUNAVAIL,1”) in new stack
– Executing [s@macro-exten-vm:10] GotoIf(“SIP/Citynet-00002da8”, “0?exit,return”) in new stack
– Executing [s@macro-exten-vm:11] Set(“SIP/Citynet-00002da8”, “SV_DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-exten-vm:12] GosubIf(“SIP/Citynet-00002da8”, “0?docfu,1”) in new stack
– Executing [s@macro-exten-vm:13] GosubIf(“SIP/Citynet-00002da8”, “0?docfb,1”) in new stack
– Executing [s@macro-exten-vm:14] Set(“SIP/Citynet-00002da8”, “DIALSTATUS=CHANUNAVAIL”) in new stack
– Executing [s@macro-exten-vm:15] NoOp(“SIP/Citynet-00002da8”, “Voicemail is ‘4102’”) in new stack
– Executing [s@macro-exten-vm:16] GotoIf(“SIP/Citynet-00002da8”, “0?s-CHANUNAVAIL,1”) in new stack
– Executing [s@macro-exten-vm:17] NoOp(“SIP/Citynet-00002da8”, “Sending to Voicemail box 4102”) in new stack
– Executing [s@macro-exten-vm:18] Macro(“SIP/Citynet-00002da8”, “vm,4102,CHANUNAVAIL,”) in new stack
– Executing [s@macro-vm:1] Macro(“SIP/Citynet-00002da8”, “user-callerid,SKIPTTL”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/Citynet-00002da8”, “AMPUSER=8146883838”) in new stack
– Executing [s@macro-user-callerid:2] GotoIf(“SIP/Citynet-00002da8”, “0?report”) in new stack
– Executing [s@macro-user-callerid:3] ExecIf(“SIP/Citynet-00002da8”, “0?Set(REALCALLERIDNUM=8146883838)”) in new stack
– Executing [s@macro-user-callerid:4] Set(“SIP/Citynet-00002da8”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/Citynet-00002da8”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:6] GotoIf(“SIP/Citynet-00002da8”, “1?report”) in new stack
– Goto (macro-user-callerid,s,10)
– Executing [s@macro-user-callerid:10] GotoIf(“SIP/Citynet-00002da8”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,19)
– Executing [s@macro-user-callerid:19] NoOp(“SIP/Citynet-00002da8”, “Using CallerID “814xxxxxxx” <814xxxxxxx>”) in new stack
– Executing [s@macro-vm:2] Set(“SIP/Citynet-00002da8”, “VMGAIN=”"") in new stack
– Executing [s@macro-vm:3] GotoIf(“SIP/Citynet-00002da8”, “1?vmx,1”) in new stack
– Goto (macro-vm,vmx,1)
– Executing [vmx@macro-vm:1] GotoIf(“SIP/Citynet-00002da8”, “0?s-CHANUNAVAIL,1”) in new stack
– Executing [vmx@macro-vm:2] Set(“SIP/Citynet-00002da8”, “MODE=unavail”) in new stack
– Executing [vmx@macro-vm:3] GotoIf(“SIP/Citynet-00002da8”, “1?notdirect”) in new stack
– Goto (macro-vm,vmx,5)
– Executing [vmx@macro-vm:5] NoOp(“SIP/Citynet-00002da8”, “Checking if ext 4102 is enabled: “) in new stack
– Executing [vmx@macro-vm:6] GotoIf(“SIP/Citynet-00002da8”, “1?s-CHANUNAVAIL,1”) in new stack
– Goto (macro-vm,s-CHANUNAVAIL,1)
– Executing [s-CHANUNAVAIL@macro-vm:1] Macro(“SIP/Citynet-00002da8”, “get-vmcontext,4102”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“SIP/Citynet-00002da8”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/Citynet-00002da8”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“SIP/Citynet-00002da8”, “”) in new stack
– Executing [s-CHANUNAVAIL@macro-vm:2] VoiceMail(“SIP/Citynet-00002da8”, “4102@default,su”””) in new stack
– <SIP/Citynet-00002da8> Playing ‘/var/spool/asterisk/voicemail/default/4102/unavail.slin’ (language ‘en’)
== Spawn extension (macro-vm, s-CHANUNAVAIL, 2) exited non-zero on ‘SIP/Citynet-00002da8’ in macro ‘vm’
== Spawn extension (macro-exten-vm, s, 18) exited non-zero on ‘SIP/Citynet-00002da8’ in macro ‘exten-vm’
== Spawn extension (from-did-direct, 4102, 1) exited non-zero on ‘SIP/Citynet-00002da8’

This is why

[quote]-- Executing [s@macro-dial:7] Dial(“SIP/Citynet-00002da8”, “SIP/4102,30,tr”) in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
== Using SIP VRTP TOS bits 136
== Using SIP VRTP CoS mark 6
== Everyone is busy/congested at this time (1:0/0/1)[/quote]

Why its busy though will not be possible from this trace, a sip trace may show it or factory reset the handset in case thats set to dnd.

Although the next steps are the same, it is actually reporting unavailable, rather than busy. Unavailable is something of a catchall!

(First is total number in the dial string, then busy, then congested, then unavailable.)