UTM5 + Radius + IVR + Cisco 5350

Технические вопросы по UTM 5.0
Ответить
Eugene B.Kravtzow
Сообщения: 46
Зарегистрирован: Сб мар 26, 2005 13:43

UTM5 + Radius + IVR + Cisco 5350

Сообщение Eugene B.Kravtzow »

Имеется UTM5 установленная под FreeBSD и IVR (netup_debit_card_ivr.1.0.0.tcl)
настройки на Циске:
#sh run | i call application
call application voice debit tftp://xxx.xxx.xxx.xxx/ivr/netup_debit_card_ivr.1.0.0.tcl
call application voice debit uid-len 4
call application voice debit pin-len 8
call application voice debit language 1 ru
call application voice debit set-location ru 0 tftp://xxx.xxx.xxx.xxx/ivr/prompts/ru_netup/

При попытке позвонить на номер IVR - просят ввести пин-код, ввожу его в различных вариантах, и получаю отказ в регистрации (ответ неправильный пин код)

Логи радиуса:

?Debug : Aug 05 10:11:10 AuthServer: User <0012> connecting
?Debug : Aug 05 10:11:10 AuthServer: Session for sessionid <0012> not found in <xxx.xxx.xxx.xxx> cache
ERROR : Aug 05 10:11:10 RADIUS DBA: Can't find login <0012>
?Debug : Aug 05 10:11:10 RADIUS DBA: Searching card login <000000012>
ERROR : Aug 05 10:11:10 RADIUS DBA: Can't find card login <000000012>
?Debug : Aug 05 10:11:10 AuthServer: Attempt to add new Card user: <0012>
?Debug : Aug 05 10:11:10 RADIUS DBA: Sending Auto-Add Request for Card-ID: 0012
?Debug : Aug 05 10:11:10 AuthServer: Got AutoAdd 0 UID from core.
Info : Aug 05 10:11:10 AuthServer: Retry:0 to find user <0012>
ERROR : Aug 05 10:11:10 RADIUS DBA: Can't find login <0012>
?Debug : Aug 05 10:11:10 RADIUS DBA: Searching card login <000000012>
ERROR : Aug 05 10:11:10 RADIUS DBA: Can't find card login <000000012>
?Debug : Aug 05 10:11:10 RADIUS Stream[plugin]: Got User-Autoadd id <0>
?Debug : Aug 05 10:11:10 RadiusSocket: RADIUS packet successfully received
?Debug : Aug 05 10:11:10 RadiusSocket: RADIUS raw data obtained
?Debug : Aug 05 10:11:10 RADIUS Packet: Size <512>; HDR.Size <512>
?Debug : Aug 05 10:11:10 AcctServer: Recv...

Логи ядра при этом:
?Debug : Aug 05 10:13:02 Radius: id2core removing key <0>
?Debug : Aug 05 10:13:07 UTM5 DBA: DBAccess instance created
?Debug : Aug 05 10:13:07 DBA:Ctx: Looking for free context
?Debug : Aug 05 10:13:07 DBCtx: SQL query: BEGIN
?Debug : Aug 05 10:13:07 DBCtx: DB transaction begin
?Debug : Aug 05 10:13:07 DBCtx: SQL SELECT query: SELECT id,pool_id,secret,balance,currency,expiration,days,is_used,service_id,is_blocked FROM card_info WHERE secret='0012'
?Debug : Aug 05 10:13:07 DBCtx: SQL SELECT query: SELECT value FROM utm5_settings WHERE variable='card_user_prefix'
?Debug : Aug 05 10:13:07 DBCtx: SQL SELECT query: SELECT id,pool_id,secret,balance,currency,expiration,days,is_used,service_id,is_blocked FROM card_info WHERE id='12'
?Debug : Aug 05 10:13:07 DBCtx: SQL SELECT query: SELECT pool_id,cards,cards_used,first_update,last_update FROM card_pool_info WHERE pool_id='2'
?Debug : Aug 05 10:13:07 DBCtx: SQL SELECT query: SELECT user_id FROM card_pool_owners WHERE pool_id ='2' AND is_deleted = 0
?Debug : Aug 05 10:13:07 DBCtx: SQL SELECT query: SELECT id,pool_id,secret,balance,currency,expiration,days,is_used,service_id,is_blocked FROM card_info WHERE id='12'
Warn : Aug 05 10:13:07 UTM5 DBA: add_user_card_priv: bad password for card <12>
?Debug : Aug 05 10:13:07 UTM5 DBA: info.secret:<12121333> password:<>
?Debug : Aug 05 10:13:07 DBCtx: SQL query: COMMIT
?Debug : Aug 05 10:13:07 DBCtx: DB transaction commit

В логах видно, что неверный пароль, но как я его только не пытался вводить, верным он не становится.
На подскажете в какую сторону копать???
Заранее огромное спасибо.

Eugene B.Kravtzow
Сообщения: 46
Зарегистрирован: Сб мар 26, 2005 13:43

Re: UTM5 + Radius + IVR + Cisco 5350

Сообщение Eugene B.Kravtzow »

Eugene B.Kravtzow писал(а):Имеется UTM5 установленная под FreeBSD и IVR (netup_debit_card_ivr.1.0.0.tcl)
настройки на Циске:
#sh run | i call application
call application voice debit tftp://xxx.xxx.xxx.xxx/ivr/netup_debit_card_ivr.1.0.0.tcl
call application voice debit uid-len 4
call application voice debit pin-len 8
call application voice debit language 1 ru
call application voice debit set-location ru 0 tftp://xxx.xxx.xxx.xxx/ivr/prompts/ru_netup/

Заранее огромное спасибо.
Карточка начала создаваться после замены с
call application voice debit uid-len 4
на
call application voice debit uid-len 8

а дальше началось еще веселее:
один и тотже звонок, лог ядра:
?Debug : Aug 05 13:10:26 UTM5 DBA: DBAccess instance created
?Debug : Aug 05 13:10:26 DBA:Ctx: Looking for free context
?Debug : Aug 05 13:10:26 DBCtx: SQL query: BEGIN
?Debug : Aug 05 13:10:26 DBCtx: DB transaction begin
?Debug : Aug 05 13:10:26 DBCtx: SQL SELECT query: SELECT id,pool_id,secret,balance,currency,expiration,days,is_used,service_id,is_blocked FROM card_info WHERE secret='12121333'
?Debug : Aug 05 13:10:26 UTM5 DBA: Found card by pin. card_id <12>
?Debug : Aug 05 13:10:26 DBCtx: SQL SELECT query: SELECT value FROM utm5_settings WHERE variable='card_user_prefix'
?Debug : Aug 05 13:10:26 DBCtx: SQL SELECT query: SELECT id,pool_id,secret,balance,currency,expiration,days,is_used,service_id,is_blocked FROM card_info WHERE id='12'
?Debug : Aug 05 13:10:26 DBCtx: SQL SELECT query: SELECT pool_id,cards,cards_used,first_update,last_update FROM card_pool_info WHERE pool_id='2'
?Debug : Aug 05 13:10:26 DBCtx: SQL SELECT query: SELECT user_id FROM card_pool_owners WHERE pool_id ='2' AND is_deleted = 0
?Debug : Aug 05 13:10:26 DBCtx: SQL SELECT query: SELECT id,pool_id,secret,balance,currency,expiration,days,is_used,service_id,is_blocked FROM card_info WHERE id='12'
Warn : Aug 05 13:10:26 UTM5 DBA: add_user_card_priv: card <12> is already used
?Debug : Aug 05 13:10:26 DBCtx: SQL query: COMMIT
?Debug : Aug 05 13:10:26 DBCtx: DB transaction commit
?Debug : Aug 05 13:10:26 DBA:Ctx: Pushing back free context
-Stats : Aug 05 13:10:26 UTM5 DBA: Stats: Uptime: 00:00:00. Events: 0; Errors: 0
?Debug : Aug 05 13:10:28 Radius: edit_session_log id <0> input octets <0> output octet <25880>
?Debug : Aug 05 13:10:28 Radius: session log with id <0> found in the cache
?Debug : Aug 05 13:10:28 DBCtx: SQL query: UPDATE tel_sessions_log SET account_id='0', slink_id='0', last_update_date='1217927428', Acct_Status_Type='2', Ac

лог радиуса в этот момент:
?Debug : Aug 05 13:10:26 AuthServer: User <12121333> connecting
?Debug : Aug 05 13:10:26 AuthServer: Session for sessionid <12121333> not found in <81.24.82.74> cache
ERROR : Aug 05 13:10:26 RADIUS DBA: Can't find login <12121333>
?Debug : Aug 05 13:10:26 RADIUS DBA: Searching card login <012121333>
ERROR : Aug 05 13:10:26 RADIUS DBA: Can't find card login <012121333>
?Debug : Aug 05 13:10:26 AuthServer: Attempt to add new Card user: <12121333>
?Debug : Aug 05 13:10:26 RADIUS DBA: Sending Auto-Add Request for Card-ID: 12121333
Info : Aug 05 13:10:26 AuthServer: Retry:0 to find user <12121333>
ERROR : Aug 05 13:10:26 RADIUS DBA: Can't find login <12121333>
?Debug : Aug 05 13:10:26 RADIUS DBA: Searching card login <012121333>
ERROR : Aug 05 13:10:26 RADIUS DBA: Can't find card login <012121333>


Видно что ядро клиента видит, а радиус почему-то нет (((

Ответить