Падает RADIUS сервер
Падает RADIUS сервер
Всем доброго времени суток! У меня такая проблема... После начала дампа базы с помощью 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. Заранее благодарю за помощь.
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. Заранее благодарю за помощь.
Re: Падает RADIUS сервер
Лог с дебагом хотелось бы увидеть.Может max_request_time = 30 или немного более должно помочь.
max_request_time Максимальное время (в секундах) используемое для обработки запроса.
Re: Падает RADIUS сервер
Вот кусок дебага перед самым падением:
Параметр max_request_time имеет значение 30.
Код: Выделить всё
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
-
- Site Admin
- Сообщения: 5746
- Зарегистрирован: Пт янв 28, 2005 3:11 pm
- Контактная информация:
Re: Падает RADIUS сервер
cat /tmp/sql_errors
Re: Падает RADIUS сервер
Код: Выделить всё
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
-
- Site Admin
- Сообщения: 5746
- Зарегистрирован: Пт янв 28, 2005 3:11 pm
- Контактная информация:
Re: Падает RADIUS сервер
обновите систему
Re: Падает RADIUS сервер
Обновился, а по поводу падения радиуса (во время дампа базы данных) может кто нибудь что нибудь подсказать?
-
- Site Admin
- Сообщения: 5746
- Зарегистрирован: Пт янв 28, 2005 3:11 pm
- Контактная информация:
Re: Падает RADIUS сервер
елси у вас тип таблиц myisam конвертруйте в innodb
Re: Падает RADIUS сервер
они как раз были сконвертированы в innodb, а проблема решилась добавлением опции --single-transaction при дампе.
Re: Падает RADIUS сервер
І як вирішити цю проблему???
Re: Падает RADIUS сервер
Проблему пока решили сделав реплику, бекапы теперь делаем с нее. Есть еще вариант собрать радиус другой версии....
-
- Site Admin
- Сообщения: 5746
- Зарегистрирован: Пт янв 28, 2005 3:11 pm
- Контактная информация:
Re: Падает RADIUS сервер
какая версия билинга ?conrad писал(а):Проблему пока решили сделав реплику, бекапы теперь делаем с нее. Есть еще вариант собрать радиус другой версии....
размер таблицы errors_log ?
Re: Падает RADIUS сервер
Таблица errors_log содержит 383164 строки и ее вес составляет 61мб. Версия биллинга 0.55 01.11.2013.
Re: Падает RADIUS сервер
Помилка не зникає!
-
- Site Admin
- Сообщения: 5746
- Зарегистрирован: Пт янв 28, 2005 3:11 pm
- Контактная информация:
Re: Падает RADIUS сервер
обновитесь к текущей и посмотрите оптимизацию базы и потоки в радиусе сколько разрешенноconrad писал(а):Таблица errors_log содержит 383164 строки и ее вес составляет 61мб. Версия биллинга 0.55 01.11.2013.