Тестируем 5.3.002

Технические вопросы по UTM 5.0
Ответить
Pei0t
Сообщения: 258
Зарегистрирован: Чт дек 13, 2007 20:48

Сообщение Pei0t »

serjk писал(а):
Проблема заключается в том, что сервис utm5_dhcpd получает все обновления лицевых счетов, даже те, которые ему в принципе не нужны. Биллинг работает, происходят списания за трафик - utm5_dhcpd получает информацию об обновлении лицевых счетов.

Признаю это незначительной проблемой, mantis ID будет зарегистрирован. Пока рекомендую настроить ротацию логов utm5_dhcpd
Тоже сыпется лог 5.3-002u8, в changelog к u9 решения не увидели.

Код: Выделить всё

Oct 14 17:01:26 ?Debug : 29807600 Transport: got ACCOUNT_UNBLOCKED for #3742
Oct 14 17:01:26 ?Debug : 29807600 StreamConnection: Got message ID 0x5010
Oct 14 17:01:26 ?Debug : 29807600 Transport: got ACCOUNT_UNBLOCKED for #3906
Oct 14 17:01:26 ?Debug : 29807600 StreamConnection: Got message ID 0x5010
Oct 14 17:01:26 ?Debug : 29807600 Transport: got ACCOUNT_UNBLOCKED for #9722
Oct 14 17:01:26 ?Debug : 29807600 StreamConnection: Got message ID 0x5010
Oct 14 17:01:26 ?Debug : 29807600 Transport: got ACCOUNT_UNBLOCKED for #1774
Oct 14 17:01:26 ?Debug : 29807600 StreamConnection: Got message ID 0x5010
Oct 14 17:01:26 ?Debug : 29807600 Transport: got ACCOUNT_UNBLOCKED for #215
Oct 14 17:01:26 ?Debug : 29807600 StreamConnection: Got message ID 0x5010
Oct 14 17:01:26 ?Debug : 29807600 Transport: got ACCOUNT_UNBLOCKED for #8156
Oct 14 17:01:26 ?Debug : 29807600 StreamConnection: Got message ID 0x5010
Oct 14 17:01:26 ?Debug : 29807600 Transport: got ACCOUNT_UNBLOCKED for #8156
Oct 14 17:01:26 ?Debug : 29807600 StreamConnection: Got message ID 0x5010
Oct 14 17:01:26 ?Debug : 29807600 Transport: got ACCOUNT_UNBLOCKED for #8286
Oct 14 17:01:27 ?Debug : 29807600 StreamConnection: Got message ID 0x5010
Oct 14 17:01:27 ?Debug : 29807600 Transport: got ACCOUNT_UNBLOCKED for #7676
Oct 14 17:01:27 ?Debug : 29807600 StreamConnection: Got message ID 0x500f
Oct 14 17:01:27 ?Debug : 29807600 Transport: got ACCOUNT_BLOCKED for #4560

banec
Сообщения: 269
Зарегистрирован: Вт сен 11, 2007 09:06

Сообщение banec »

serjk писал(а):viewtopic.php?p=63500#63500
Спасибо. помогло (как я прозевал- пересматривал же ветку)

Тестирую DHCP
Если по МАС выдать на прямую ip пользователю получается.
Через профиль оборудования 2-а дня безрезультатно.

Используем HP2626,2610, DCN и SNR (82 - передаём в формате HP
т.е.

Код: Выделить всё

ip forward-protocol udp bootps
ip dhcp relay information option
ip dhcp relay information option subscriber-id format vs-hp
ip dhcp relay information option remote-id format vs-hp
)

использую ISC DHCPD сервер
class "10.4.0.245" {
match if option agent.remote-id = ac:10:01:41 and option agent.circuit-id = 00:03;
}
remote-id = IP vlan с которого идёт запрос в HEX
circuit-id = номер порта HEX
приходят от DCN(SNR) такой пакет если смотрел через тспдамп

Код: Выделить всё

    Option: (82) Agent Information Option
        Length: 10
        Option 82 Suboption: (1) Agent Circuit ID
            Length: 2
            Agent Circuit ID: 0003  - третий порт
        Option 82 Suboption: (2) Agent Remote ID
            Length: 4
            Agent Remote ID: ac100141  - по идее ip влана комутатора
    Option: (255) End
        Option End: 255
    Padding
пробовал как строка - не помогает
т.е. вроде как без смещений MAC идлина 8 и порт 2 и 2 и писать в явном виде т.е. Remote ID = ac100141 или я что-то не то понимаю?


Помогите настроить или кто уже настраивал на данное оборудование?

up.
option [dhcp-message-type]: 01
option [dhcp-client-identifier]: 018c89a5a6f3f0
option [host-name]: WIN-77IO1R0ORVS
option [dhcp-class-identifier]: MSFT 5.0
option [dhcp-parameter-request-list]: 010f03062c2e2f1f2179f92b
option [relay-agent-info]: 010200030204ac100141

banec
Сообщения: 269
Зарегистрирован: Вт сен 11, 2007 09:06

Сообщение banec »

Вообщем после долгих усилий пришли к

Изображение

везде "Опция 82"
и RemoteID 6-4
Порт 3-1 (не знаю ещё как поведет и в каком формате писать при портах 10 и более - нет пока под рукой 24-х портового )

+1 косяк = нет проверки привязки IP-static порт+коммутатор - т.е. в связаках можно установить одинаковый порт на несколько связок, может это в некоторых моментах при динамических ip и верно, но при статике не годится.

banec
Сообщения: 269
Зарегистрирован: Вт сен 11, 2007 09:06

Сообщение banec »

Выше 10 портов отработало всё ок.

теперь про косяки
Изображение

имеем в связке 2-е группы адресов настроенные на разные порты коммутатора в итоге во время изменения одной из групп выдался ip другой с учетом что порт не совпадает!!!

Аватара пользователя
ZeM
Сообщения: 371
Зарегистрирован: Чт фев 17, 2011 08:38

Сообщение ZeM »

Чудный косяк .....
И так вчера было так
Изображение

Сегодня в 7 утра товарищ попал в системную блокировку.
У него висел кредит!
Начало блокировки

Код: Выделить всё

Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2014_01'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2013_12'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2013_11'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2013_10'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2013_09'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2014_02'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2014_03'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2014_04'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2014_05'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2014_06'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2014_07'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2014_08'
Oct 16 07:33:20 ?Debug : f9465700 UTM5 DBA: archive tables listing: table 'UTM5arc.ptr_2014_09'
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL SELECT query&#58; SELECT id from payment_transactions WHERE id='46671'
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL SELECT query&#58; 1 rows in 0.000 sec
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query&#58; UPDATE credits SET status=2 WHERE id='2916'
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query takes 0.001 sec
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query&#58; UPDATE payment_transactions SET comments_for_admins='CREDIT FIRED' WHERE id='46671'
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query takes 0.001 sec
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query&#58; INSERT INTO blocks_info &#40;account_id,block_type,start_date,expire_date,is_planning,flags&#41; VALUES &#40;'4','1','1413430400','2000000000','0','0'&#41;
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query takes 0.001 sec
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query&#58; UPDATE accounts SET block_id='29955' WHERE id='4'
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query takes 0.001 sec
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 StreamConnection&#58; Sending message ID 0x2102
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 RADIUS_Module&#58; &#91;3&#93;&#58; account ID <4>  was sent
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 StreamConnection&#58; Sending message ID 0x500f
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DHCP_Module&#58; account #4 update has been sent to &#91;4&#93;
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query&#58; UPDATE blocks_info SET start_date='1413430400',expire_date='2000000000',is_planning='0' WHERE id='29955'
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query takes 0.001 sec
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query&#58; UPDATE periodic_service_links SET start_block_unabon='0',start_block_unprepay='0',start_block_untel='0' WHERE id='5'
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query takes 0.001 sec
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query&#58; UPDATE periodic_service_links SET start_block_unabon='0',start_block_unprepay='0',start_block_untel='0' WHERE id='3013'
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query takes 0.001 sec
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query&#58; UPDATE accounts SET int_status='0' WHERE id='4'
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 DBConnection_mysql&#58; <0x1fa71f0> SQL query takes 0.001 sec
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 TransactionQueueManager&#58; push&#58; using queue &#91;1&#93;
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 TransactionQueueManager&#58; push&#58; transaction ptr &#91;0x7fe7f46bdcd0&#93; handler #2 ptr &#91;0x7fe7f448a1b0&#93;
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 TransactionHandlerImpl&#58; push&#58; transaction ptr &#91;f46bdcd0&#93; &#40;size 1, Events &#91; 17 &#93;&#41;
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 TransactionQueueManager&#58; push&#58; using queue &#91;1&#93;
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 TransactionQueueManager&#58; push&#58; transaction ptr &#91;0x281d000&#93; handler #2 ptr &#91;0x7fe7f448a1b0&#93;
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 TransactionHandlerImpl&#58; push&#58; transaction ptr &#91;0281d000&#93; &#40;size 1, Events &#91; 26 &#93;&#41;
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 StreamConnection&#58; Sending message ID 0x2102
Oct 16 07&#58;33&#58;20 ?Debug &#58; f9465700 RADIUS_Module&#58; &#91;3&#93;&#58; account ID <3830>  was sent
Вот дебаг сегодняшний радиуса когда его вышибло.

Код: Выделить всё

ct 16 07&#58;40&#58;02 ?Debug &#58; 9c6de700 Tarifficator&#58; got account ID 4 balance -49.1118 credit 2550 block_id 29955
Oct 16 07&#58;40&#58;02 ?Debug &#58; 9c6de700 DAC&#58; Disconnect-Request&#58; 
--- RADIUS Pkt ---
  Code&#58; &#91;40&#93;  ID&#58;   &#91;142&#93;
  Auth&#58; Size 16; Data &#91;0x00000000000000000000000000000000&#93;
    Attr&#58; &#91;1&#93; Vendor&#58; &#91;0&#93; Size 5; Data &#91;0x6175746f31&#93;
        &#40;User-Name=STRING&#58;auto1&#41;
    Attr&#58; &#91;44&#93; Vendor&#58; &#91;0&#93; Size 19; Data &#91;0x333433303632382d766c616e3830302d343132&#93;
        &#40;Acct-Session-Id=STRING&#58;3430628-vlan800-412&#41;
    Attr&#58; &#91;8&#93; Vendor&#58; &#91;0&#93; Size 4; Data &#91;0xc0a8c809&#93;
        &#40;Framed-IP-Address=IP&#58;192.168.200.9&#41;
    Attr&#58; &#91;31&#93; Vendor&#58; &#91;0&#93; Size 47; Data &#91;0x32303a63663a33303a38383a30643a6138202f2032303a63663a33303a38383a30643a6138202f20766c616e383030&#93;
        &#40;Calling-Station-Id=STRING&#58;20&#58;cf&#58;30&#58;88&#58;0d&#58;a8 / 20&#58;cf&#58;30&#58;88&#58;0d&#58;a8 / vlan800&#41;

Oct 16 07&#58;40&#58;02 ?Debug &#58; 9c6de700 DAC&#58; Disconnect-Request has been sent to NAS 172.28.100.254 for SID '3430628-vlan800-412' login 'auto1'
Oct 16 07&#58;40&#58;02 ?Debug &#58; 9c6de700 StreamConnection&#58; Got message ID 0x2102
Oct 16 07&#58;40&#58;02 ?Debug &#58; 9c6de700 Transport&#58; got ACCOUNT_INFO event
Вот билинг

Код: Выделить всё

ct 16 07&#58;40&#58;02 ?Debug &#58; f8c5d700 DBACharge&#58; charge 0 for link 3013 account 4
Oct 16 07&#58;40&#58;02 ?Debug &#58; f8c5d700 DBConnection_mysql&#58; <0x1fa71f0> SQL query&#58; UPDATE accounts SET balance='-49.1118221283' WHERE id='4'
Oct 16 07&#58;40&#58;02 ?Debug &#58; f8c5d700 DBConnection_mysql&#58; <0x1fa71f0> SQL query takes 0.000 sec
Oct 16 07&#58;40&#58;02 ?Debug &#58; f8c5d700 DBConnection_mysql&#58; <0x1fa71f0> SQL query&#58; INSERT INTO discount_transactions_all&#40;account_id,incoming_rest,outgoing_rest,discount,discount_with_tax,service_id,service_type,slink_id,discount_date,charge_type,discount_period_id&#41; VALUES &#40;'4','-49.1118221283','-49.1118221283','0','0','131','3','3013','1413430802','3','54'&#41;
Oct 16 07&#58;40&#58;02 ?Debug &#58; f8c5d700 DBConnection_mysql&#58; <0x1fa71f0> SQL query takes 0.000 sec

Код: Выделить всё

Oct 16 07&#58;42&#58;03 ?Debug &#58; f8c5d700 DBACharge&#58; charge 0 for link 3013 account 4
Oct 16 07&#58;42&#58;03 ?Debug &#58; f8c5d700 DBConnection_mysql&#58; <0x1f8ea60> SQL query&#58; UPDATE accounts SET balance='-49.1118221283' WHERE id='4'
Oct 16 07&#58;42&#58;03 ?Debug &#58; f8c5d700 DBConnection_mysql&#58; <0x1f8ea60> SQL query takes 0.000 sec
Oct 16 07&#58;42&#58;03 ?Debug &#58; f8c5d700 DBConnection_mysql&#58; <0x1f8ea60> SQL query&#58; INSERT INTO discount_transactions_all&#40;account_id,incoming_rest,outgoing_rest,discount,discount_with_tax,service_id,service_type,slink_id,discount_date,charge_type,discount_period_id&#41; VALUES &#40;'4','-49.1118221283','-49.1118221283','0','0','131','3','3013','1413430923','3','54'&#41;
Oct 16 07&#58;42&#58;03 ?Debug &#58; f8c5d700 DBConnection_mysql&#58; <0x1f8ea60> SQL query takes 0.000 sec
Почему так произошло? Пост дополнил еще дебагами.

banec
Сообщения: 269
Зарегистрирован: Вт сен 11, 2007 09:06

Сообщение banec »

upd9
косяк или фича, но при добавлении минимального расчетного периода
3600 сек и более - и время начала 00-00 - переходит в is_expired =1
т.е. нужно указать текущее время как минимум.

Код: Выделить всё

141016 15&#58;22&#58;31    19 Connect   billing@172.16.1.55 on UTM5
                   19 Query     SET NAMES latin1
                   19 Query     SELECT MAX&#40;static_id&#41; FROM discount_periods
                   19 Query     INSERT INTO discount_periods&#40;start_date,end_date,periodic_type,canonical_len,custom_duration,discount_interval,static_id,next_discount_period_id, invoice_month&#41; VALUES &#40;'1413417600','1413421278','1048576','3678','3678','0','49','0','0'&#41;
                   20 Connect   billing@172.16.1.55 on UTM5
                   20 Query     SET NAMES latin1
                   20 Query     UPDATE discount_periods SET is_expired='1' WHERE id='2193'

banec
Сообщения: 269
Зарегистрирован: Вт сен 11, 2007 09:06

Сообщение banec »

Расчетные периоды произвольной длины не повторяются !!!
пропадают


зы вообщем это всё таки фича :lol: если к расчетному периоду не привязана хоть одна услуга она убирается из списка

Pei0t
Сообщения: 258
Зарегистрирован: Чт дек 13, 2007 20:48

Сообщение Pei0t »

СОздали метод платежа "оплата через кассу"
Изображение
Вот теперь что в отчете по платежам
Изображение
А это баг или фича?

5.3.-002u08

banec
Сообщения: 269
Зарегистрирован: Вт сен 11, 2007 09:06

Сообщение banec »

на 5.3.-002u09 воспроизвел - тоже самое в отчетах 100 вместо названия.

Pei0t
Сообщения: 258
Зарегистрирован: Чт дек 13, 2007 20:48

Сообщение Pei0t »

banec писал(а):на 5.3.-002u09 воспроизвел - тоже самое в отчетах 100 вместо названия.
У нас рестартанулось ядро и стало писаться как положено.

banec
Сообщения: 269
Зарегистрирован: Вт сен 11, 2007 09:06

Сообщение banec »

После игр с NAS и его атрибутами и вкл/выкл интернета(блокировки) абонента получил такое

Код: Выделить всё

Oct 24 13&#58;48&#58;28  ERROR &#58; ca40f700 UTM5 DBA&#58; charge policy #1 not found
Oct 24 13&#58;48&#58;28  ERROR &#58; ca40f700 DBAExistingError&#58; charge policy not found
Oct 24 13&#58;48&#58;28  ERROR &#58; ca40f700 RPCServer@0.0.0.0&#58; void* rpcf_save_account&#40;void*&#41;&#58; DBAExistingError&#58; charge policy not found
charge policy #1 - у меня и не было!!! после конвертации(апгрейде) появилось 3 и 5
у абонентов в тарифах-услугах стоит дефаул 3

banec
Сообщения: 269
Зарегистрирован: Вт сен 11, 2007 09:06

Сообщение banec »

накатил обратно базу - при апдейте на up8 - в логох было пусто
на up9
получил результат выше
+
ещё такое
Oct 24 16:26:26 ERROR : b3458700 UTM5 DBA: charge policy #1 not found
Oct 24 16:26:26 ERROR : b3458700 DBAExistingError: charge policy not found
Oct 24 16:26:26 ERROR : b3458700 EventScheduler: event handling error: DBAExistingError: charge policy not found
Oct 24 16:26:26 ERROR : b3458700 DBAExistingError: get_account_iter: such account not exist 544435823
Oct 24 16:26:26 ERROR : b3458700 EventScheduler: event handling error: DBAExistingError: get_account_iter: such account not exist 544435823

banec
Сообщения: 269
Зарегистрирован: Вт сен 11, 2007 09:06

Сообщение banec »

продолжаем залил-апдейтил-конвертировал блокировки
получаем
Oct 24 22:37:11 ?Debug : aa25d740 DBConnection_mysql: <0xd91750> SQL query: UPDATE periodic_service_links SET policy_id='1' WHERE id='38354'
Oct 24 22:37:11 ?Debug : aa25d740 DBConnection_mysql: <0xd91750> SQL query takes 0.042 sec
Oct 24 22:37:11 ?Debug : aa25d740 DBConnection_mysql: <0xd91750> SQL query: UPDATE periodic_service_links SET policy_id='1' WHERE id='38353'
Oct 24 22:37:11 ?Debug : aa25d740 DBConnection_mysql: <0xd91750> SQL query takes 0.042 sec
Oct 24 22:37:11 ?Debug : aa25d740 DBConnection_mysql: <0xd91750> SQL query: INSERT INTO charge_policy(name,flags) VALUES ('default_policy','0'),('policy_2','56')
Oct 24 22:37:11 ?Debug : aa25d740 DBConnection_mysql: <0xd91750> SQL query takes 0.042 sec
Oct 24 22:37:11 ?Debug : aa25d740 BlockConverter: Conversion completed successfully
<LAST LINE>
всем апдейтится SET policy_id='1' и 2
но в charge_policy вставляется id 3 и 5
Поле вроде как автоинкримент - следующее вообще 7
чёто я не догоняю.

Подправил руками id - запустил - в маин.лог уже не сыпется вся эта ерунда

Аватара пользователя
ZeM
Сообщения: 371
Зарегистрирован: Чт фев 17, 2011 08:38

Сообщение ZeM »

А будет ли админка с Москва GMT +3?
Сейчас jre-8u25-windows-i586 + последняя админка = Moscow GMT +4
Вообщем выбрал Минск.

seacat
Сообщения: 4
Зарегистрирован: Вс окт 26, 2014 12:00

Сообщение seacat »

ZeM писал(а):А будет ли админка с Москва GMT +3?
Сейчас jre-8u25-windows-i586 + последняя админка = Moscow GMT +4
Вообщем выбрал Минск.
обновить таймзону явы на клиенте.

Ответить