Падает RADIUS сервер

Ответить
conrad
Сообщения: 69
Зарегистрирован: Пн окт 14, 2013 3:41 pm

Падает RADIUS сервер

Сообщение conrad »

Всем доброго времени суток! У меня такая проблема... После начала дампа базы с помощью mysqldump, примерно через 10-15 секунд падает Radius, в логе появляются вот такие строчки:

Tue Dec 24 04:19:37 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 37470 - ID: 240 due to unfinished request 3046
Tue Dec 24 04:19:37 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 65091 - ID: 44 due to unfinished request 3034
Tue Dec 24 04:19:37 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 52322 - ID: 94 due to unfinished request 3035
Tue Dec 24 04:19:38 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 59235 - ID: 157 due to unfinished request 3036
Tue Dec 24 04:19:38 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 54771 - ID: 107 due to unfinished request 3037
Tue Dec 24 04:19:38 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 11364 - ID: 148 due to unfinished request 3039
Tue Dec 24 04:19:38 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 13355 - ID: 23 due to unfinished request 3048
Tue Dec 24 04:19:39 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 15330 - ID: 223 due to unfinished request 3051
Tue Dec 24 04:19:39 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 50225 - ID: 197 due to unfinished request 3030
Tue Dec 24 04:19:39 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 29692 - ID: 182 due to unfinished request 3031
Tue Dec 24 04:19:40 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 45145 - ID: 53 due to unfinished request 3044
Tue Dec 24 04:19:40 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 61441 - ID: 3 due to unfinished request 3054
Tue Dec 24 04:19:41 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 37470 - ID: 240 due to unfinished request 3046
Tue Dec 24 04:19:41 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 65091 - ID: 44 due to unfinished request 3034
Tue Dec 24 04:19:41 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 52322 - ID: 94 due to unfinished request 3035
Tue Dec 24 04:19:41 2013 : Error: Received conflicting packet from client 10.17.0.100 port 33489 - ID: 14 due to unfinished request 3056. Giving up on old request.
Еще было такое:
Tue Dec 24 00:07:46 2013 : Info: WARNING: Child is hung for request 4294649 in component <core> module <queue>.
Tue Dec 24 00:07:46 2013 : Info: WARNING: Child is hung for request 4292627 in component <core> module <queue>.
Tue Dec 24 00:07:46 2013 : Error: WARNING: Unresponsive thread 4293904 for request 0, in component <core> module <queue>
Tue Dec 24 00:07:46 2013 : Error: Discarding duplicate request from client 10.17.0.100 port 20913 - ID: 20 due to unfinished request 4294596
Tue Dec 24 00:07:46 2013 : Info: WARNING: Child is hung for request 4293905 in component <core> module <queue>.
Tue Dec 24 00:07:46 2013 : Error: Received conflicting packet from client 10.17.0.100 port 20794 - ID: 241 due to unfinished request 4294763. Giving up on ol
d request.

Может ли кто нибудь что нибудь посоветовать? Версия abills - 0.55 из cvs, radius 2.2.2, субд mysql 5.6.14, ось FreeBSD 9.2-release. Заранее благодарю за помощь.

snooppy
Сообщения: 37
Зарегистрирован: Пн апр 29, 2013 7:20 am

Re: Падает RADIUS сервер

Сообщение snooppy »

Лог с дебагом хотелось бы увидеть.Может max_request_time = 30 или немного более должно помочь.
max_request_time Максимальное время (в секундах) используемое для обработки запроса.

conrad
Сообщения: 69
Зарегистрирован: Пн окт 14, 2013 3:41 pm

Re: Падает RADIUS сервер

Сообщение conrad »

Вот кусок дебага перед самым падением:

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

Fri Dec 27 00:46:16 2013 : Debug: Received Access-Request packet from host 10.17.0.100 port 48768, id=3, length=210
Fri Dec 27 00:46:16 2013 : Debug:       NAS-Identifier = "NAS1"
Fri Dec 27 00:46:16 2013 : Debug:       Acct-Session-Id = "8097336-L-7"
Fri Dec 27 00:46:16 2013 : Debug:       NAS-Port = 7
Fri Dec 27 00:46:16 2013 : Debug:       NAS-Port-Type = Ethernet
Fri Dec 27 00:46:16 2013 : Debug:       Service-Type = Framed-User
Fri Dec 27 00:46:16 2013 : Debug:       Framed-Protocol = PPP
Fri Dec 27 00:46:16 2013 : Debug:       Calling-Station-Id = "58:94:6b:6a:e7:98"
Fri Dec 27 00:46:16 2013 : Debug:       NAS-Port-Id = "em1"
Fri Dec 27 00:46:16 2013 : Debug:       mpd-link = "L-7"
Fri Dec 27 00:46:16 2013 : Debug:       Tunnel-Medium-Type:0 = IEEE-802
Fri Dec 27 00:46:16 2013 : Debug:       Tunnel-Client-Endpoint:0 = "58:94:6b:6a:e7:98"
Fri Dec 27 00:46:16 2013 : Debug:       User-Name = "fl5-8-37"
Fri Dec 27 00:46:16 2013 : Debug:       CHAP-Challenge = 0xbb1e686458c772e0d01fbe6c0f548999f6c1a81185b4f64d25b9ff818ad1a574e403d93ccb4b1c9b6ada07792f91
Fri Dec 27 00:46:16 2013 : Debug:       CHAP-Password = 0x01d9f497488b93c340b1cc8818ff73c729
Fri Dec 27 00:46:16 2013 : Debug: # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/abills_default
Fri Dec 27 00:46:16 2013 : Debug: +group authorize {
Fri Dec 27 00:46:16 2013 : Debug: ++[preprocess] = ok
Fri Dec 27 00:46:16 2013 : Debug: ++[mschap] = noop
Fri Dec 27 00:46:16 2013 : Debug: [files] users: Matched entry DEFAULT at line 38
Fri Dec 27 00:46:16 2013 : Debug: ++[files] = ok
Fri Dec 27 00:46:16 2013 : Debug: Received Accounting-Request packet from host 10.17.0.100 port 44960, id=172, length=427
Fri Dec 27 00:46:16 2013 : Debug:       NAS-Identifier = "NAS1"
Fri Dec 27 00:46:16 2013 : Debug:       Acct-Session-Id = "7988375-L-274"
Fri Dec 27 00:46:16 2013 : Debug:       NAS-Port = 274
Fri Dec 27 00:46:16 2013 : Debug:       NAS-Port-Type = Ethernet
Fri Dec 27 00:46:16 2013 : Debug:       Service-Type = Framed-User
Fri Dec 27 00:46:16 2013 : Debug:       Framed-Protocol = PPP
Fri Dec 27 00:46:16 2013 : Debug:       Calling-Station-Id = "00:19:99:68:90:b4"
Fri Dec 27 00:46:16 2013 : Debug:       NAS-Port-Id = "em1"
Fri Dec 27 00:46:16 2013 : Debug:       mpd-link = "L-274"
Fri Dec 27 00:46:16 2013 : Debug:       Tunnel-Medium-Type:0 = IEEE-802
Fri Dec 27 00:46:16 2013 : Debug:       Tunnel-Client-Endpoint:0 = "00:19:99:68:90:b4"
Fri Dec 27 00:46:16 2013 : Debug:       Framed-IP-Address = 91.234.219.23
Fri Dec 27 00:46:16 2013 : Debug:       Framed-IP-Netmask = 255.255.255.255
Fri Dec 27 00:46:16 2013 : Debug:       User-Name = "fl5-2-72"
Fri Dec 27 00:46:16 2013 : Debug:       Acct-Multi-Session-Id = "7988375-B-143"
Fri Dec 27 00:46:16 2013 : Debug:       mpd-bundle = "B-143"
Fri Dec 27 00:46:16 2013 : Debug:       mpd-iface = "ng142"
Fri Dec 27 00:46:16 2013 : Debug:       mpd-iface-index = 153
...skipping...
Fri Dec 27 00:46:39 2013 : Debug:       Acct-Status-Type = Interim-Update
Fri Dec 27 00:46:39 2013 : Debug:       Acct-Session-Time = 246304
Fri Dec 27 00:46:39 2013 : Debug:       Acct-Input-Octets = 509083787
Fri Dec 27 00:46:39 2013 : Debug:       Acct-Input-Packets = 2297134
Fri Dec 27 00:46:39 2013 : Debug:       Acct-Input-Gigawords = 0
Fri Dec 27 00:46:39 2013 : Debug:       Acct-Output-Octets = 1650214018
Fri Dec 27 00:46:39 2013 : Debug:       Acct-Output-Packets = 2531080
Fri Dec 27 00:46:39 2013 : Debug:       Acct-Output-Gigawords = 0
Fri Dec 27 00:46:39 2013 : Debug:       mpd-input-octets = "1:93197435"
Fri Dec 27 00:46:39 2013 : Debug:       mpd-input-packets = "1:1534858"
Fri Dec 27 00:46:39 2013 : Debug:       mpd-input-octets = "0:409680751"
Fri Dec 27 00:46:39 2013 : Debug:       mpd-input-packets = "0:750978"
Fri Dec 27 00:46:39 2013 : Debug:       mpd-output-octets = "1:910113686"
Fri Dec 27 00:46:39 2013 : Debug:       mpd-output-packets = "1:1637881"
Fri Dec 27 00:46:39 2013 : Debug:       mpd-output-octets = "0:729894320"
Fri Dec 27 00:46:39 2013 : Debug:       mpd-output-packets = "0:882991"
Fri Dec 27 00:46:39 2013 : Debug: # Executing section preacct from file /usr/local/etc/raddb/sites-enabled/abills_default
Fri Dec 27 00:46:39 2013 : Debug: +group preacct {
Fri Dec 27 00:46:39 2013 : Debug: ++[preprocess] = ok
Fri Dec 27 00:46:39 2013 : Debug: +} # group preacct = ok
Fri Dec 27 00:46:39 2013 : Debug: # Executing section accounting from file /usr/local/etc/raddb/sites-enabled/abills_default
Fri Dec 27 00:46:39 2013 : Debug: +group accounting {
Fri Dec 27 00:46:39 2013 : Debug: Cleaning up request 3562 ID 57 with timestamp +528
Fri Dec 27 00:46:39 2013 : Debug: Cleaning up request 3563 ID 233 with timestamp +528
Fri Dec 27 00:46:39 2013 : Debug: [perl] Child is finally responsive for request 3419
Fri Dec 27 00:46:40 2013 : Debug: Cleaning up request 3567 ID 225 with timestamp +529
Fri Dec 27 00:46:40 2013 : Debug: Cleaning up request 3569 ID 104 with timestamp +529
Fri Dec 27 00:46:40 2013 : Debug: Cleaning up request 3570 ID 247 with timestamp +529
Fri Dec 27 00:46:40 2013 : Debug: [perl] Child is finally responsive for request 3426
Fri Dec 27 00:46:40 2013 : Debug: [perl] Child is finally responsive for request 3432
Fri Dec 27 00:46:41 2013 : Debug: [perl] Child is finally responsive for request 3442
Fri Dec 27 00:46:41 2013 : Debug: [perl] Child is finally responsive for request 3445
Fri Dec 27 00:46:41 2013 : Debug: [perl] Child is finally responsive for request 3447
Fri Dec 27 00:46:41 2013 : Debug: [perl] Child is finally responsive for request 3455
Fri Dec 27 00:46:41 2013 : Debug: [perl] Child is finally responsive for request 3460
Fri Dec 27 00:46:42 2013 : Debug: [perl] Child is finally responsive for request 3464
Fri Dec 27 00:46:42 2013 : Debug: [perl] Child is finally responsive for request 3465
Fri Dec 27 00:46:42 2013 : Debug: [perl] Child is finally responsive for request 3469
Fri Dec 27 00:46:42 2013 : Debug: [perl] Child is finally responsive for request 3473
Fri Dec 27 00:46:42 2013 : Debug: [perl] Child is finally responsive for request 3474
Параметр max_request_time имеет значение 30.

~AsmodeuS~
Site Admin
Сообщения: 5746
Зарегистрирован: Пт янв 28, 2005 3:11 pm
Контактная информация:

Re: Падает RADIUS сервер

Сообщение ~AsmodeuS~ »

cat /tmp/sql_errors

conrad
Сообщения: 69
Зарегистрирован: Пн окт 14, 2013 3:41 pm

Re: Падает RADIUS сервер

Сообщение conrad »

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

2013-12-24 00:07:46 LOG_ERR:  []
DELETE FROM web_online WHERE UNIX_TIMESTAMP()-logtime>3000;
 --1690
 --BIGINT UNSIGNED value is out of range in '(unix_timestamp() - `abills`.`web_online`.`logtime`)'
 --AutoCommit: 1

2013-12-24 02:45:13 LOG_ERR:  []
SELECT u.id AS login, u.deleted, l.acct_session_id, l.uid
    FROM dv_log l
    INNER JOIN users u ON (u.uid=l.uid)
    WHERE (u.id='fl5-11-58') and l.uid='1466' and (u.id='fl5-11-58') and date_format(start, '%Y-%m')=date_format(curdate(), '%Y-%m')  and u.deleted='0'
    ORDER BY 5 DESC LIMIT 0, 25;
 --1054
 --Unknown column '5' in 'order clause'
 --AutoCommit: 1

2013-12-24 02:45:47 LOG_ERR:  []
SELECT u.id AS login, u.deleted, l.acct_session_id, l.uid
    FROM dv_log l
    INNER JOIN users u ON (u.uid=l.uid)
    WHERE (u.id='fl5-11-58') and l.uid='1466' and (u.id='fl5-11-58') and u.deleted='0'
    ORDER BY 7 DESC LIMIT 0, 25;
 --1054
 --Unknown column '7' in 'order clause'
 --AutoCommit: 1

2013-12-24 02:47:46 LOG_ERR:  []
SELECT u.id AS login, u.deleted, l.acct_session_id, l.uid
    FROM dv_log l
    INNER JOIN users u ON (u.uid=l.uid)
    WHERE (u.id='fl5-11-58') and l.uid='1466' and (u.id='fl5-11-58') and u.deleted='0'
    ORDER BY 7 DESC LIMIT 0, 25;
 --1054
 --Unknown column '7' in 'order clause'
 --AutoCommit: 1

2013-12-24 16:42:29 LOG_ERR:  []
SELECT u.id AS login, u.deleted, l.acct_session_id, l.uid
    FROM dv_log l
    INNER JOIN users u ON (u.uid=l.uid)
    WHERE (u.id='fl5-11-58') and l.uid='1466' and (u.id='fl5-11-58') and u.deleted='0'
    ORDER BY 7 DESC LIMIT 0, 25;
 --1054
 --Unknown column '7' in 'order clause'
 --AutoCommit: 1

2013-12-26 16:15:35 LOG_ERR:  []
SELECT u.id AS login, u.deleted, l.acct_session_id, l.uid
    FROM dv_log l
    INNER JOIN users u ON (u.uid=l.uid)
    WHERE (u.id='fl5-1-54') and l.uid='62' and (u.id='fl5-1-54') and u.deleted='0'
    ORDER BY 7 DESC LIMIT 0, 25;
 --1054
 --Unknown column '7' in 'order clause'
 --AutoCommit: 1

~AsmodeuS~
Site Admin
Сообщения: 5746
Зарегистрирован: Пт янв 28, 2005 3:11 pm
Контактная информация:

Re: Падает RADIUS сервер

Сообщение ~AsmodeuS~ »

обновите систему

conrad
Сообщения: 69
Зарегистрирован: Пн окт 14, 2013 3:41 pm

Re: Падает RADIUS сервер

Сообщение conrad »

Обновился, а по поводу падения радиуса (во время дампа базы данных) может кто нибудь что нибудь подсказать?

~AsmodeuS~
Site Admin
Сообщения: 5746
Зарегистрирован: Пт янв 28, 2005 3:11 pm
Контактная информация:

Re: Падает RADIUS сервер

Сообщение ~AsmodeuS~ »

елси у вас тип таблиц myisam конвертруйте в innodb

conrad
Сообщения: 69
Зарегистрирован: Пн окт 14, 2013 3:41 pm

Re: Падает RADIUS сервер

Сообщение conrad »

они как раз были сконвертированы в innodb, а проблема решилась добавлением опции --single-transaction при дампе.

abcoleh
Сообщения: 57
Зарегистрирован: Ср июл 03, 2013 9:46 am

Re: Падает RADIUS сервер

Сообщение abcoleh »

І як вирішити цю проблему???

conrad
Сообщения: 69
Зарегистрирован: Пн окт 14, 2013 3:41 pm

Re: Падает RADIUS сервер

Сообщение conrad »

Проблему пока решили сделав реплику, бекапы теперь делаем с нее. Есть еще вариант собрать радиус другой версии....

~AsmodeuS~
Site Admin
Сообщения: 5746
Зарегистрирован: Пт янв 28, 2005 3:11 pm
Контактная информация:

Re: Падает RADIUS сервер

Сообщение ~AsmodeuS~ »

conrad писал(а):Проблему пока решили сделав реплику, бекапы теперь делаем с нее. Есть еще вариант собрать радиус другой версии....
какая версия билинга ?
размер таблицы errors_log ?

conrad
Сообщения: 69
Зарегистрирован: Пн окт 14, 2013 3:41 pm

Re: Падает RADIUS сервер

Сообщение conrad »

Таблица errors_log содержит 383164 строки и ее вес составляет 61мб. Версия биллинга 0.55 01.11.2013.

abcoleh
Сообщения: 57
Зарегистрирован: Ср июл 03, 2013 9:46 am

Re: Падает RADIUS сервер

Сообщение abcoleh »

Помилка не зникає!

~AsmodeuS~
Site Admin
Сообщения: 5746
Зарегистрирован: Пт янв 28, 2005 3:11 pm
Контактная информация:

Re: Падает RADIUS сервер

Сообщение ~AsmodeuS~ »

conrad писал(а):Таблица errors_log содержит 383164 строки и ее вес составляет 61мб. Версия биллинга 0.55 01.11.2013.
обновитесь к текущей и посмотрите оптимизацию базы и потоки в радиусе сколько разрешенно

Ответить