NO JOY Can't use "firstnotonphone" after update (older system)

[rchalk]

Running FreePBX 2.9.0.15, Centos 5.5, Asterisk 1.4.41 (PBXinaFlash Silver). Using a ring group with two extensions, set up as firstnotonphone. This used to work fine, until some updates were made to modules in webmin. Now, trying to call that same ring group from an Aastra 6757i, I get a "Sieze Failed" message, and a fast busy signal. If I change to firstavailable or ringall, the phones ring as expected.

I expect that some update in Asterisk has messed this up, but I have no idea which one(s) are responsible. Can anyone help? Thanks.

 

[rossiv]

Running module updates in Webmin shouldn't have broken Asterisk directly, but stranger things have happened when running yum updates. Please post some call logs.

 

[rchalk]

OK, I think the attached file is the start through the end. To simplify, I dialed the ring group 1102 directly from extension 1001.Here are the last 500 lines, but I'm not sure if this is enough. If not, I will try again, and get more lines.

Thanks for offering to help.

 

[rossiv]

Interesting!

[2015-03-28 16:50:11] VERBOSE[12428] logger.c: == Manager 'admin' logged on from 127.0.0.1
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: dialparties.agi: Caller ID name is 'Richard Office' number is '1001'
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: dialparties.agi: Methodology of ring is 'firstnotonphone'
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: -- dialparties.agi: Added extension 1002 to extension map
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: -- dialparties.agi: Added extension 1003 to extension map
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: -- dialparties.agi: Extension 1002 cf is disabled
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: -- dialparties.agi: Extension 1003 cf is disabled
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: -- dialparties.agi: Extension 1002 do not disturb is disabled
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: -- dialparties.agi: Extension 1003 do not disturb is disabled
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: dialparties.agi: ExtensionState: -1
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: dialparties.agi: Extension 1002 has ExtensionState: -1
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: dialparties.agi: Extension 1002 has call waiting enabled but blocked for hunt
...snip...
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: dialparties.agi: ExtensionState: -1
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: dialparties.agi: Extension 1003 has ExtensionState: -1
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: dialparties.agi: Extension 1003 has call waiting enabled but blocked for hunt
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: -- dialparties.agi: Filtered ARG3:
[2015-03-28 16:50:11] VERBOSE[12428] logger.c: == Manager 'admin' logged off from 127.0.0.1
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: -- AGI Script dialparties.agi completed, returning 0
[2015-03-28 16:50:11] DEBUG[12422] app_macro.c: Executed application: AGI
[2015-03-28 16:50:11] VERBOSE[12422] logger.c: -- Executing [s@macro-dial:4] NoOp("SIP/1001-00000026", "Returned from dialparties with no extensions to call and DIALSTATUS: BUSY") in new stack
[2015-03-28 16:50:11] DEBUG[12422] app_macro.c: Executed application: NoOp

So it looks like you (Ext 1001) called RG 1102. RG 1102 has members 1002 and 1003. Something fishy is up with those two. Never seen "ExtensionState" before. That next to last line is your true error message though. Something is causing Asterisk to not like 1002 and 1003 in that RG.

Edit: Apparently ExtensionState of -1 means that the extension can't be found. Do those phones work otherwise?

 

[rchalk]

Yes, as I said earlier, if I change the ring group to "ringfirstavailable", or "ringall" everything works normally. It's only the "firstnotonphone" that gives the problem. It has to be related to the system determining the state of each extension as being onphone, or notonphone.

Would it help to post a log file in one of these two cases for comparison?