Автор: FlyingSfinks
Дата сообщения: 21.11.2009 02:41
Cитуация следующая:
настраиваю узел для предоставления телефонии по карточкам.
Всё запустил. УТМ 5.2.1_006_demo (что не критично пока), Cisco AS5300, FreeBSD7.2-RELEASE, UTM-RADIUS
Сгенерировал карты, Дозвонился, ввёл пинкод. В ответ слышу "неверный пинкод".
Лезу в админку, смотрю что карта добавилась. Смотрю в её свойствах - нет услуги.
Создал фиктивную услугу, скопировал в неё цены с основной. Поставил галочку "добавлять по умолчанию". В базовой услуге эта галочка не активна.
При вводе пинкода уже другой карты, она добавилась, услуга завелась, но в ответ всё же пишет, что "неправильно введен пинкод.
Да ещё карты при автодобавлени (автоактивации) в списке красными нарисованы, если изменить в них параметр "Интернет" на "включен", то карточные пользователи становятся зелеными. Тем не менее на результат ввода пинкода это не влияет.
Вот что в Debug.log
Код:
?Debug : Nov 19 12:03:36 Radius: init_session_log id <2>
?Debug : Nov 19 12:03:36 DBCtx: <679506944> SQL query: INSERT INTO tel_sessions_log(account_id,slink_id,recv_date,last_update_date, NAS_Port,Acct_Delay_Time, Acct_Session_Id,NAS_Port_Type,User_Name,Service_Type,Framed_Protocol,NAS_IP_Address,NAS_Id,Acct_Status_Type, Acct_Input_Packets,Acct_Input_Octets,Acct_Input_Gigawords,Acct_Output_Packets,Acct_Output_Octets,Acct_Output_Gigawords, Acct_Session_Time,Acct_Terminate_Cause,Called_Station_Id,Calling_Station_Id,h323_remote_address,h323_conf_id, h323_setup_time,h323_call_origin, h323_call_type,h323_connect_time,h323_disconnect_time,h323_disconnect_cause, h323_gw_id,zone_id,did,session_start_date) VALUES('3','3','1258625016','1258625016','0','0','00000042','0','74012','1','0','50243776','192.168.254.2','1','0','0','0','0','0','0','0','0','570235','74012','','h323-conf-id=A348F4B4 D42911DE 803BAE22 418E4AC0','h323-setup-time=12\:03\:36.592 EET Thu Nov 19 2009','h323-call-origin=answer','h323-call-type=Telephony','','','','h323-gw-id=Router.','0','0','1258625016')
?Debug : Nov 19 12:03:36 Radius: Dynamic IP binding not called because ip or slink is null ! ip <0> slink <3>
?Debug : Nov 19 12:03:50 UTM5 DBA: DBAccess instance created
?Debug : Nov 19 12:03:50 DBA:Ctx: Looking for free context
?Debug : Nov 19 12:03:50 DBCtx: <679515136> SQL query: BEGIN
?Debug : Nov 19 12:03:50 DBCtx: DB transaction begin
?Debug : Nov 19 12:03:50 DBCtx: <679515136> SQL SELECT query: SELECT id,pool_id,secret,balance,currency,expiration,days,is_used,service_id,is_blocked FROM card_info WHERE secret='000397717964'
?Debug : Nov 19 12:03:50 DBCtx: <679515136> SQL SELECT query: SELECT value FROM utm5_settings WHERE variable='card_user_prefix'
?Debug : Nov 19 12:03:50 DBCtx: <679515136> SQL SELECT query: SELECT id,pool_id,secret,balance,currency,expiration,days,is_used,service_id,is_blocked FROM card_info WHERE id='3'
?Debug : Nov 19 12:03:50 DBCtx: <679515136> SQL SELECT query: SELECT pool_id,cards,cards_used,first_update,last_update FROM card_pool_info WHERE pool_id='1'
?Debug : Nov 19 12:03:50 DBCtx: <679515136> SQL SELECT query: SELECT user_id FROM card_pool_owners WHERE pool_id ='1' AND is_deleted = 0
?Debug : Nov 19 12:03:50 DBCtx: <679515136> SQL SELECT query: SELECT id,pool_id,secret,balance,currency,expiration,days,is_used,service_id,is_blocked FROM card_info WHERE id='3'
Warn : Nov 19 12:03:50 UTM5 DBA: add_user_card_priv: card <3> is already used
?Debug : Nov 19 12:03:50 DBCtx: <679515136> SQL query: COMMIT
?Debug : Nov 19 12:03:50 DBCtx: DB transaction commit
?Debug : Nov 19 12:03:50 DBA:Ctx: Pushing back free context
-Stats : Nov 19 12:03:50 UTM5 DBA: Stats: Uptime: 00:00:00. Events: 0; Errors: 0
?Debug : Nov 19 12:03:56 DBCtx: <679506944> SQL query: INSERT INTO tel_sessions_detail(dhs_sess_id,disc_per_id,slink_id,recv_date,account_id,trange_id,duration,base_cost,sum_cost) VALUES('10','3','3','1258625036','3','0','20','0','0')
?Debug : Nov 19 12:03:56 UTM5 DBA: DBAccess instance created
?Debug : Nov 19 12:03:56 DBA:Ctx: Looking for free context
?Debug : Nov 19 12:03:56 DBCtx: <679511040> SQL query: BEGIN
?Debug : Nov 19 12:03:56 DBCtx: DB transaction begin
?Debug : Nov 19 12:03:56 UTM5 DBA: discount_from_account tel zoneid <0> h.base_cost <0.000000>
?Debug : Nov 19 12:03:56 DBCtx: <679506944> SQL query: UPDATE dtagg_telephony SET discounted='0.000000',discounted_without_tax='0.000000', duration='68' WHERE id='1'
?Debug : Nov 19 12:03:56 UTM5 DBA: Charge:0.000000 p.u. for link:3 account 3
?Debug : Nov 19 12:03:56 DBCtx: <679511040> SQL query: UPDATE accounts SET balance='1' WHERE id = '3'
?Debug : Nov 19 12:03:56 DBCtx: <679511040> SQL query: INSERT INTO discount_transactions_all(account_id,incoming_rest,outgoing_rest,discount,discount_with_tax,service_id,service_type,slink_id,discount_date,charge_type,discount_period_id) VALUES('3','1','1','0','0','1','6','3','1258625036','6','3')
?Debug : Nov 19 12:03:56 UTM5 DBA: Checking for rehash: flags 3, balance 1.000 (old 1.000), credit 0.000
?Debug : Nov 19 12:03:56 DBAGiga: Rehash: Target: 10; Action: 1; Ptr: bc7e8a0c
?Debug : Nov 19 12:03:56 Radius: Account '3': 1.000/0.000 send...
?Debug : Nov 19 12:03:56 HSPlugin: Rehash: Target: 10; Action: 1; Ptr: bc7e8a0c
?Debug : Nov 19 12:03:56 BusLogic: BLM(37) pushed (comment: ruh block)
?Debug : Nov 19 12:03:56 DBCtx: <679511040> SQL query: COMMIT
?Debug : Nov 19 12:03:56 BusLogic: try to execute 37
?Debug : Nov 19 12:03:56 BusLogic: hw_block_handler with code 37
?Debug : Nov 19 12:03:56 BusLogic: finished unknown
?Debug : Nov 19 12:03:56 DBCtx: DB transaction commit
?Debug : Nov 19 12:03:56 DBA:Ctx: Pushing back free context
-Stats : Nov 19 12:03:56 UTM5 DBA: Stats: Uptime: 00:00:00. Events: 0; Errors: 0
?Debug : Nov 19 12:03:56 Radius: edit_session_log id <2> input octets <0> output octet <60480>
?Debug : Nov 19 12:03:56 Radius: session log with id <2> found in the cache
?Debug : Nov 19 12:03:56 DBCtx: <679506944> SQL query: UPDATE tel_sessions_log SET account_id='3', slink_id='3', last_update_date='1258625036', Acct_Status_Type='2', Acct_Input_Packets ='0', Acct_Input_Octets ='0', Acct_Input_Gigawords ='0', Acct_Output_Packets ='252', Acct_Output_Octets ='60480', Acct_Output_Gigawords ='0', h323_connect_time='', Acct_Terminate_Cause='0', h323_disconnect_cause='h323-disconnect-cause=11', Acct_Session_Time ='20' WHERE id='10'
?Debug : Nov 19 12:03:56 Radius: id2core removing key <2>