One extension is always engaged

PBX Firmware: 1.1005.210.58-1
PBX Service Pack: 1.0.0.0

I have a Freepbx system with about 20 extensions on it. one of them ext 124 always goes to voice mail busy message despite the fact that its not busy. the phone is an aastra 9133i, the problem started after the phone was moved. It went into web recovery mode, I uploaded new firmware and the phone looked OK but thats when the busy problem started. I also can’t dial out from it. The display shows call failed and no data appears on the screen when you view asterisk -rvvvvvvvvvvvv for the outgoing call.

I have tried deleteing and re-instating the extension but it was no better.

I set up a new extension for the phone 130. I changed the phone to this extension the phone can receive calls but is still the same for outgoing calls.

I replaced the phone with a cisco but still can’t get it to work on extension 124, it will dial out but still is busy for incoming calls.

show sip peers gives the same result for this phone as the others.

I set the cisco phone to be ext 130 and it works fine for both out going and incoming calls.

I suspect that something is wrong with both the phone and the system, its not the end of the world if the phone has died but I’d like to be able to use ext 124 again.

any advice please…

the log of a call to ext 124 follows.

-- Executing [[email protected]:1] Set("SIP/111-0000008e", "__RINGTIMER=15") in new stack
-- Executing [[email protected]:2] Macro("SIP/111-0000008e", "exten-vm,novm,124,0,0,0") in new stack
-- Executing [[email protected]:1] Macro("SIP/111-0000008e", "user-callerid,") in new stack
-- Executing [[email protected]:1] Set("SIP/111-0000008e", "AMPUSER=111") in new stack
-- Executing [[email protected]:2] GotoIf("SIP/111-0000008e", "0?report") in new stack
-- Executing [[email protected]:3] ExecIf("SIP/111-0000008e", "1?Set(REALCALLERIDNUM=111)") in new stack
-- Executing [[email protected]:4] Set("SIP/111-0000008e", "AMPUSER=111") in new stack
-- Executing [[email protected]:5] Set("SIP/111-0000008e", "AMPUSERCIDNAME=Steven") in new stack
-- Executing [[email protected]:6] GotoIf("SIP/111-0000008e", "0?report") in new stack
-- Executing [[email protected]:7] Set("SIP/111-0000008e", "AMPUSERCID=111") in new stack
-- Executing [[email protected]:8] Set("SIP/111-0000008e", "CALLERID(all)="Steven" <111>") in new stack
-- Executing [[email protected]:9] GotoIf("SIP/111-0000008e", "0?limit") in new stack
-- Executing [[email protected]:10] ExecIf("SIP/111-0000008e", "0?Set(GROUP(concurrency_limit)=111)") in new stack
-- Executing [[email protected]:11] GosubIf("SIP/111-0000008e", "7?sub-ccss,s,1(macro-exten-vm,124)") in new stack
-- Executing [[email protected]:1] ExecIf("SIP/111-0000008e", "0?Return()") in new stack
-- Executing [[email protected]:2] Set("SIP/111-0000008e", "CCSS_SETUP=TRUE") in new stack
-- Executing [[email protected]:3] GosubIf("SIP/111-0000008e", "0?monitor_config,1(macro-exten-vm,124):monitor_default,1(macro-exten-vm,124)") in new stack
-- Executing [[email protected]:1] GotoIf("SIP/111-0000008e", "1?is_exten") in new stack
-- Goto (sub-ccss,monitor_default,4)
-- Executing [[email protected]:4] Set("SIP/111-0000008e", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack
-- Executing [[email protected]:5] Set("SIP/111-0000008e", "CALLCOMPLETION(cc_max_monitors)=5") in new stack
-- Executing [[email protected]:6] Return("SIP/111-0000008e", "TRUE") in new stack
-- Executing [[email protected]:4] GosubIf("SIP/111-0000008e", "7?agent_config,1():agent_default,1()") in new stack
-- Executing [[email protected]:1] Set("SIP/111-0000008e", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack
-- Executing [[email protected]:2] Set("SIP/111-0000008e", "CALLCOMPLETION(cc_offer_timer)=30") in new stack
-- Executing [[email protected]:3] Set("SIP/111-0000008e", "CALLCOMPLETION(ccbs_available_timer)=") in new stack

[2012-10-09 14:48:21] WARNING[17975]: ccss.c:936 ast_set_ccbs_available_timer: 0 is an invalid value for ccbs_available_timer. Retaining value as 4800
– Executing [[email protected]:4] Set(“SIP/111-0000008e”, “CALLCOMPLETION(ccnr_available_timer)=”) in new stack
[2012-10-09 14:48:21] WARNING[17975]: ccss.c:906 ast_set_ccnr_available_timer: 0 is an invalid value for ccnr_available_timer. Retaining value as 7200
– Executing [[email protected]:5] Set(“SIP/111-0000008e”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
– Executing [[email protected]:6] ExecIf(“SIP/111-0000008e”, “1?Set(CALLCOMPLETION(cc_recall_timer)=)”) in new stack
[2012-10-09 14:48:21] WARNING[17975]: ccss.c:921 ast_set_cc_recall_timer: 0 is an invalid value for ccnr_available_timer. Retaining value as 20
– Executing [[email protected]:7] ExecIf(“SIP/111-0000008e”, “1?Set(CALLCOMPLETION(cc_max_agents)=)”) in new stack
– Executing [[email protected]:8] ExecIf(“SIP/111-0000008e”, “0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/[email protected])”) in new stack
– Executing [[email protected]:9] Set(“SIP/111-0000008e”, “CALLCOMPLETION(cc_callback_macro)=ccss-default”) in new stack
– Executing [[email protected]:10] Return(“SIP/111-0000008e”, “”) in new stack
– Executing [[email protected]:5] Set(“SIP/111-0000008e”, “DB(AMPUSER/111/ccss/last_number)=124”) in new stack
– Executing [[email protected]:6] Return(“SIP/111-0000008e”, “”) in new stack
– Executing [[email protected]:12] ExecIf(“SIP/111-0000008e”, “0?Set(CHANNEL(language)=)”) in new stack
– Executing [[email protected]:13] GotoIf(“SIP/111-0000008e”, “0?continue”) in new stack
– Executing [[email protected]:14] Set(“SIP/111-0000008e”, “__TTL=64”) in new stack
– Executing [[email protected]:15] GotoIf(“SIP/111-0000008e”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,26)
– Executing [[email protected]:26] Set(“SIP/111-0000008e”, “CALLERID(number)=111”) in new stack
– Executing [[email protected]:27] Set(“SIP/111-0000008e”, “CALLERID(name)=Steven”) in new stack
– Executing [[email protected]:28] Set(“SIP/111-0000008e”, “CHANNEL(language)=en”) in new stack
– Executing [[email protected]:2] Set(“SIP/111-0000008e”, “RingGroupMethod=none”) in new stack
– Executing [[email protected]:3] Set(“SIP/111-0000008e”, “__EXTTOCALL=124”) in new stack
– Executing [[email protected]:4] Set(“SIP/111-0000008e”, “__PICKUPMARK=124”) in new stack
– Executing [[email protected]:5] Set(“SIP/111-0000008e”, “RT=”) in new stack
– Executing [[email protected]:6] Gosub(“SIP/111-0000008e”, “sub-record-check,s,1(exten,124,)”) in new stack
– Executing [[email protected]:1] GotoIf(“SIP/111-0000008e”, “1?check”) in new stack
– Goto (sub-record-check,s,6)
– Executing [[email protected]:6] Set(“SIP/111-0000008e”, “__MON_FMT=wav”) in new stack
– Executing [[email protected]:7] GotoIf(“SIP/111-0000008e”, “1?next”) in new stack
– Goto (sub-record-check,s,10)
– Executing [[email protected]:10] ExecIf(“SIP/111-0000008e”, “0?Return()”) in new stack
– Executing [[email protected]:11] GotoIf(“SIP/111-0000008e”, “0?exten,1”) in new stack
– Executing [[email protected]:12] Set(“SIP/111-0000008e”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [[email protected]:13] ExecIf(“SIP/111-0000008e”, “0?Set(__REC_POLICY_MODE=)”) in new stack
– Executing [[email protected]:14] Set(“SIP/111-0000008e”, “NOW=1349790501”) in new stack
– Executing [[email protected]:15] Set(“SIP/111-0000008e”, “__DAY=09”) in new stack
– Executing [[email protected]:16] Set(“SIP/111-0000008e”, “__MONTH=10”) in new stack
– Executing [[email protected]:17] Set(“SIP/111-0000008e”, “__YEAR=2012”) in new stack
– Executing [[email protected]:18] Set(“SIP/111-0000008e”, “__TIMESTR=20121009-144821”) in new stack
– Executing [[email protected]:19] Set(“SIP/111-0000008e”, “__FROMEXTEN=111”) in new stack
– Executing [[email protected]:20] Set(“SIP/111-0000008e”, “__CALLFILENAME=exten-124-111-20121009-144821-1349790501.464”) in new stack
– Executing [[email protected]:21] Goto(“SIP/111-0000008e”, “exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [[email protected]:1] GotoIf(“SIP/111-0000008e”, “0?callee”) in new stack
– Executing [[email protected]:2] Set(“SIP/111-0000008e”, “__REC_POLICY_MODE=dontcare”) in new stack
– Executing [[email protected]:3] GotoIf(“SIP/111-0000008e”, “1?caller”) in new stack
– Goto (sub-record-check,exten,10)
– Executing [[email protected]:10] Set(“SIP/111-0000008e”, “REC_POLICY_MODE=dontcare”) in new stack
– Executing [[email protected]:11] GosubIf(“SIP/111-0000008e”, “0?record,1(exten,124,111)”) in new stack
– Executing [[email protected]:12] Return(“SIP/111-0000008e”, “”) in new stack
– Executing [[email protected]:7] GotoIf(“SIP/111-0000008e”, “1?macrodial”) in new stack
– Goto (macro-exten-vm,s,13)
– Executing [[email protected]:13] GosubIf(“SIP/111-0000008e”, “0?clrheader,1()”) in new stack
– Executing [[email protected]:14] Macro(“SIP/111-0000008e”, “dial-one,tr,124”) in new stack
– Executing [[email protected]:1] Set(“SIP/111-0000008e”, “DEXTEN=124”) in new stack
– Executing [[email protected]:2] Set(“SIP/111-0000008e”, “DIALSTATUS_CW=”) in new stack
– Executing [[email protected]:3] GosubIf(“SIP/111-0000008e”, “0?screen,1()”) in new stack
– Executing [[email protected]:4] GosubIf(“SIP/111-0000008e”, “0?cf,1()”) in new stack
– Executing [[email protected]:5] GotoIf(“SIP/111-0000008e”, “0?skip1”) in new stack
– Executing [[email protected]:6] Set(“SIP/111-0000008e”, “DEXTEN=”) in new stack
– Executing [[email protected]:7] Set(“SIP/111-0000008e”, “DIALSTATUS=BUSY”) in new stack
– Executing [[email protected]:8] GotoIf(“SIP/111-0000008e”, “1?nodial”) in new stack
– Goto (macro-dial-one,s,46)
– Executing [[email protected]:46] ExecIf(“SIP/111-0000008e”, “0?Set(DIALSTATUS=NOANSWER)”) in new stack
– Executing [[email protected]:47] NoOp(“SIP/111-0000008e”, “Returned from dial-one with nothing to call and DIALSTATUS: BUSY”) in new stack
– Executing [[email protected]:48] MacroExit(“SIP/111-0000008e”, “”) in new stack
– Executing [[email protected]:15] Set(“SIP/111-0000008e”, “SV_DIALSTATUS=BUSY”) in new stack
– Executing [[email protected]:16] GosubIf(“SIP/111-0000008e”, “0?docfu,1()”) in new stack
– Executing [[email protected]:17] GosubIf(“SIP/111-0000008e”, “0?docfb,1()”) in new stack
– Executing [[email protected]:18] Set(“SIP/111-0000008e”, “DIALSTATUS=BUSY”) in new stack
– Executing [[email protected]:19] ExecIf(“SIP/111-0000008e”, “0?MacroExit()”) in new stack
– Executing [[email protected]:20] GotoIf(“SIP/111-0000008e”, “1?s-BUSY,1”) in new stack
– Goto (macro-exten-vm,s-BUSY,1)
– Executing [[email protected]:1] GotoIf(“SIP/111-0000008e”, “0?exit,1”) in new stack
– Executing [[email protected]:2] PlayTones(“SIP/111-0000008e”, “busy”) in new stack
– Executing [[email protected]:3] Busy(“SIP/111-0000008e”, “20”) in new stack
== Spawn extension (macro-exten-vm, s-BUSY, 3) exited non-zero on ‘SIP/111-0000008e’ in macro ‘exten-vm’
== Spawn extension (from-internal, 124, 2) exited non-zero on ‘SIP/111-0000008e’
– Executing [[email protected]:1] Hangup(“SIP/111-0000008e”, “”) in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on ‘SIP/111-0000008e’

I resolved the issue with the phone. I restored it to the factory defaults and set it up again. This time it worked.

So I’m just looking for advice on being able to use extension 124 again on the system. if I set a phone up as 124 then it seems to register OK but when you call it from another extension it is busy and calls go to voicemail. as per log in first post.

Dave

1 Like

look in your asteriskdb for a dnd or something against 124, from bash:-

rasterisk -x ‘database show’|grep 124

just a touch more info please. where do I enter the instruction? I get the gist I think.

if i type database show at the asterisk cli then I get a page or so full of information about each extension. the grep|124 bit will cause the output just to show lines that contain 124 but do I type the whole line exactly as you typed it at the asterisk cli?

bash:-rasterisk -x ‘database show’|grep 124

thanks Dave

1 Like