Опыт установки 5.3 на боевом сервере

[ZeM]

Ждемc update 3 - периодические тормоза админки напрягают...

[kirush]

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

43113 root       61  44    0  2160M   130M select  0 289:11  0.00% utm5_core
43141 root        9  44    0 41540K 14092K select  3  93:19  0.00% utm5_radius

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

# uname -a
FreeBSD 8.2-RELEASE FreeBSD 8.2-RELEASE #1 i386

Проблем с утечками не наблюдаю...может это поможет.

[ZeM]

Вот мои показания.

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

KiB Mem:   4055192 total,  3357856 used,   697336 free,     2460 buffers
KiB Swap:  3902456 total,    30168 used,  3872288 free,  1740180 cached

  PID USER      PR  NI  VIRT  RES  SHR S  %CPU %MEM    TIME+  COMMAND
 7464 root      20   0 1686m 1,4g 5128 S   2,3 35,8 270:43.00 utm5_core
 7503 root      20   0  332m  15m 1900 S   1,0  0,4 197:01.06 utm5_radius

ОС

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

# cat /etc/debian_version
7.2
# uname -a
Linux UTM 3.2.0-4-amd64 #1 SMP Debian 3.2.51-1 x86_64 GNU/Linux

На 15.22

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

KiB Mem:   4055192 total,  3945360 used,   109832 free,     3908 buffers
KiB Swap:  3902456 total,    34916 used,  3867540 free,  2292940 cached

  PID USER      PR  NI  VIRT  RES  SHR S  %CPU %MEM    TIME+  COMMAND
 7464 root      20   0 1689m 1,4g 4960 S   3,3 36,6 278:21.94 utm5_core
 7503 root      20   0  332m  16m 1864 S   1,7  0,4 201:37.57 utm5_radius

Вывод что памяти потребляется больше. Количество зависит от аптайма ядра.

[Point]

у меня через 5 часов после перезапуска
97642 root 45 44 0 216M 186M select 1 22:54 2.29% utm5_core

сделали отчет по трафику группы юрлица за предыдущий месяц
97642 root 45 44 0 217M 191M select 0 23:13 5.52% utm5_core

вот, конец рабочего дня в абонентском отделе (17:25 местного времени)
97642 root 51 44 0 295M 265M select 0 42:38 2.78% utm5_core

Еще 4 часа прошло
97642 root 43 44 0 409M 372M sbwait 3 74:47 5.86% utm5_core

[ZeM]

Кто знает это что?

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

Nov 05 14:13:19  ERROR : 6adec700 RADIUS_Module: [1]: LogicError: invalid IP-group format
Nov 05 14:14:10  ERROR : 6adec700 DBAIPgroup: check IP-group: empty item list
Nov 05 14:14:10  ERROR : 6adec700 LogicError: invalid IP-group format
Nov 05 14:14:10  ERROR : 6adec700 RADIUS_Module: [1]: LogicError: invalid IP-group format
Nov 05 14:18:52  ERROR : 6adec700 DBAIPgroup: check IP-group: empty item list
Nov 05 14:18:52  ERROR : 6adec700 LogicError: invalid IP-group format
Nov 05 14:18:52  ERROR : 6adec700 RADIUS_Module: [1]: LogicError: invalid IP-group format
Nov 05 14:19:47  ERROR : 6adec700 DBAIPgroup: check IP-group: empty item list
Nov 05 14:19:47  ERROR : 6adec700 LogicError: invalid IP-group format
Nov 05 14:19:47  ERROR : 6adec700 RADIUS_Module: [1]: LogicError: invalid IP-group format
Nov 05 14:41:46  ERROR : 6adec700 DBAIPgroup: check IP-group: empty item list
Nov 05 14:41:46  ERROR : 6adec700 LogicError: invalid IP-group format
Nov 05 14:41:46  ERROR : 6adec700 RADIUS_Module: [1]: LogicError: invalid IP-group format
Nov 05 14:42:09  ERROR : 6adec700 DBAIPgroup: check IP-group: empty item list
Nov 05 14:42:09  ERROR : 6adec700 LogicError: invalid IP-group format
Nov 05 14:42:09  ERROR : 6adec700 RADIUS_Module: [1]: LogicError: invalid IP-group format

[Point]

UTM5.3. 001-U3 FreeBSD8 i386
аптайм 1 час
6459 root 27 44 0 103M 84148K select 2 2:56 2.78% utm5_core

посмотрим, что будет утром

на первый взгляд админка стала работать на порядок быстрее, посмотрим что скажет абонентский отдел

в main.log посыпалось

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

Nov 06 00:25:59  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:25:59  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:00  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:00  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:00  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:06  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:06  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:06  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:08  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:08  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:09  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:09  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:10  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:13  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:16  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:16  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:16  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event
Nov 06 00:26:16  ERROR : 28cf76c0 DBAGenericError: no registered plugin for event

main-debug.log

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

Nov 06 00:30:39 ?Debug : 28cf76c0 TransactionHandlerImpl: current queue size: 1
Nov 06 00:30:39 ?Debug : 28cf76c0 TransactionHandlerImpl: incoming transaction ptr [2d6f60b0], outgoing transaction ptr [2bc1c060]
Nov 06 00:30:39 ?Debug : 28cf76c0 DBConnectionPool: DBConnectioManager pool [Default]: connection is popped
Nov 06 00&#58;30&#58;39 ?Debug &#58; 28cf76c0 PluginManagerImpl&#58; no registered plugin for event_id <95>
Nov 06 00&#58;30&#58;39  ERROR &#58; 28cf76c0 DBAGenericError&#58; no registered plugin for event
Nov 06 00&#58;30&#58;39 ?Debug &#58; 28cf76c0 TransactionHandlerImpl&#58; plugin handler for event 95 not defined, event not handled
Nov 06 00&#58;30&#58;39 ?Debug &#58; 28cf76c0 TransactionHandlerImpl&#58; some plugins not loaded due to license limitations, contact NetUP,Inc for more info
Nov 06 00&#58;30&#58;39 ?Debug &#58; 28cf76c0 TransactionFilter&#58; transaction sent to the internal queue
Nov 06 00&#58;30&#58;39 ?Debug &#58; 28cf76c0 TransactionQueueManager&#58; push&#58; using default priority queue
Nov 06 00&#58;30&#58;39 ?Debug &#58; 28cf76c0 TransactionQueueManager&#58; push&#58; transaction ptr &#91;0x2bc1c060&#93; handler #1 ptr &#91;0x2a783c40&#93;
Nov 06 00&#58;30&#58;39 ?Debug &#58; 28cf76c0 TransactionHandlerImpl&#58; push&#58; empty transaction ptr &#91;2bc1c060&#93; dropped
Nov 06 00&#58;30&#58;39 ?Debug &#58; 28cf76c0 DummyTransactionQueue&#58; commit&#58; 0 transactions
Nov 06 00&#58;30&#58;39 ?Debug &#58; 28cf76c0 DBConnectionPool&#58; DBConnectionManager pool &#91;Default&#93;&#58; connection is pushed back

UPD
аптайм 9 часов
6459 root 31 44 0 171M 145M select 0 18:45 0.39% utm5_core

еще апдейт
аптайм 23 часа, процесс продолжает жрать память

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

PID USERNAME   THR PRI NICE   SIZE    RES STATE   C   TIME   WCPU COMMAND
 6459 root        43  44    0   452M   411M select  1  84:25  4.54% utm5_core

[/code]

[ZeM]

Подтверждаю на апдейт 3 память потребляется более скромно!

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

KiB Mem:   4055192 total,  3479456 used,   575736 free,    23744 buffers
KiB Swap:  3902456 total,     5096 used,  3897360 free,  3087600 cached

  PID USER      PR  NI  VIRT  RES  SHR S  %CPU %MEM    TIME+  COMMAND
 1028 root      20   0  408m 174m 4520 S   2,0  4,4  16:12.55 utm5_core
 1055 root      20   0  266m 9880 2236 S   1,0  0,2  11:38.70 utm5_radius

P.S. Админка перестала тупить все работает быстро. Спасибо разработчикам!

[alexus]

Народ, на сколько проблемно (по вашему опыту) будет перейти с 5.2.1-006 на последнюю версию ?

[ZeM]

Я переходил с 5.2.1-008 но на***лся от души. У нас еще не все гладко от прошлого админа биллинга + нное количество багов по дороге.
Вообще для начала поставьте в стороне тестовый стенд и на нем все увидите!

[Point]

Народ, на сколько проблемно (по вашему опыту) будет перейти с 5.2.1-006 на последнюю версию ?

самое главное- определиться и въехать в процесс блокировки и снятия абонентской платы

[kirush]

Народ, на сколько проблемно (по вашему опыту) будет перейти с 5.2.1-006 на последнюю версию ?

самое главное- определиться и въехать в процесс блокировки и снятия абонентской платы

Лучше уж дождаться НГ, разработчики обещали переделать систему блокировок.

[alexus]

Народ, на сколько проблемно (по вашему опыту) будет перейти с 5.2.1-006 на последнюю версию ?

самое главное- определиться и въехать в процесс блокировки и снятия абонентской платы

А что там за тема с блокировками?

[torrus]

Спасибо за новый радиус.Теперь полный MAC передаёт.Но! Теперь какие то не понятки с атрибутами.Железка,на которой авторизовываеся тестовый клиент, в дебаг во что кидает:

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

[local]AGLS#Nov 7 09:13:07: [0001]: [2/2:511:63:31/7/2/24]: %AAA-7-RAD_ATTR: aaa_idx 50000019: rad_add_attr_to_tlv_list, Add attr NAS_Port_ID (2/2 clips 131096) with len 17 to tlv list
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 1/1 User_Name tag=32 data_type=4 vlen=17 size=19
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 1/2 User_Password tag=32 data_type=5 vlen=16 size=18
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 1/6 Service_Type tag=32 data_type=2 vlen=4 size=6
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 1/32 NAS_Identifier tag=32 data_type=4 vlen=7 size=9
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 1/5 NAS_Port tag=32 data_type=2 vlen=4 size=6
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 2352/62 NAS_Real_Port tag=32 data_type=2 vlen=4 size=6
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 1/61 NAS_Port_Type tag=32 data_type=2 vlen=4 size=6
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 1/87 NAS_Port_ID tag=32 data_type=4 vlen=16 size=18
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 2352/38 Medium_Type tag=32 data_type=2 vlen=4 size=6
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 2352/145 Mac-Address tag=32 data_type=4 vlen=17 size=19
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 2352/98 Platform_Type tag=32 data_type=2 vlen=4 size=6
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 2352/112 OS_Version tag=32 data_type=4 vlen=7 size=9
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 2352/202 DHCP_Option tag=32 data_type=5 vlen=10 size=12
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 2352/202 DHCP_Option tag=32 data_type=5 vlen=11 size=13
Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_parse_pkt: Receive RFC attr 6 (Service_Type), tag = 32, status = bad service type
Nov 7 09:13:07: %AAA-7-RAD_ATTR: val= (0)
Nov 7 09:13:07: %AAA-7-EXCEPT: rad_parse_pkt: Attribute function failed for attribute 6
Nov 7 09:13:07: [0001]: [2/2:511:63:31/7/2/24]: %AAA-7-EXCEPT: aaa_idx 50000019: rad_fill_response_attr: Fail authen. Bad attribute Service_Type. Error (6)
Nov 7 09:13:07: [0001]: [2/2:511:63:31/7/2/24]: %AAA-7-EXCEPT: aaa_idx 50000019: rad_create_auth_db_reply: Radius authentication fail: bad service type. (00:0c:42:d3:2d:f1)
Nov 7 09:13:07: [0001]: [2/2:511:63:31/7/2/24]: %AAA-7-RAD_ATTR: aaa_idx 50000019: rad_add_attr_to_tlv_list, Add attr NAS_Port_ID (2/2 clips 131096) with len 17 to tlv list

Кусок лога радиуса:

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

Nov 07 09:07:47 ?Debug : b75e6b70 AuthQueue: New request from 10.2.2.250:1812
--- RADIUS Pkt ---
  Code: [1]  ID:   [24]
  Auth: Size 16; Data [0xfda17d7d253dd2be780341698ee214af]
    Attr: [1] Vendor: [0] Size 17; Data [0x30303a30633a34323a64333a32643a6631]
        (User-Name=STRING:00:0c:42:d3:2d:f1)
    Attr: [2] Vendor: [0] Size 16; Data [0xdd28c2950894c39b30713b0f2147db9b]
        (User-Password=HEX:...)
    Attr: [6] Vendor: [0] Size 4; Data [0x00000005]
        (Service-Type=INT:5)
    Attr: [32] Vendor: [0] Size 7; Data [0x5265646261636b]
        (NAS-Identifier=STRING:Redback)
    Attr: [5] Vendor: [0] Size 4; Data [0x02020000]
        (NAS-Port=INT:33685504)
    Attr: [62] Vendor: [2352] Size 4; Data [0x02020000]
        (Redback:NAS_Real_Port=INT:33685504)
    Attr: [61] Vendor: [0] Size 4; Data [0x00000005]
        (NAS-Port-Type=INT:5)
    Attr: [87] Vendor: [0] Size 16; Data [0x322f3220636c69707320313331303937]
        (NAS-Port-Id=STRING:2/2 clips 131097)
    Attr: [38] Vendor: [2352] Size 4; Data [0x0000000b]
        (Redback:Medium_Type=INT:11)
    Attr: [-111] Vendor: [2352] Size 17; Data [0x30302d30632d34322d64332d32642d6631]
        (Redback:Mac-Addr=STRING:00-0c-42-d3-2d-f1)
    Attr: [98] Vendor: [2352] Size 4; Data [0x00000004]
        (Redback:Platform-Type=INT:4)
    Attr: [112] Vendor: [2352] Size 7; Data [0x362e352e312e35]
        (Redback:OS-Version=STRING:6.5.1.5)
    Attr: [-54] Vendor: [2352] Size 10; Data [0x3d3d0701000c42d32df1]
        (Redback:DHCP-Option=STRING:==Nov 07 09:07:47 ?Debug : b75e6b70 AuthQueue: Login '00:0c:42:d3:2d:f1'
Nov 07 09:07:47 ?Debug : b75e6b70 LoginStorage: Acquire: login '00:0c:42:d3:2d:f1' used 1 times
Nov 07 09:07:47 ?Debug : b75e6b70 AuthQueue: Login info found, slink_id 38
Nov 07 09:07:47 ?Debug : b75e6b70 AuthQueue: Using PAP authentication method
Nov 07 09:07:47 ?Debug : b75e6b70 AuthQueue: PAP authentication OK
Nov 07 09:07:47 ?Debug : b75e6b70 AuthQueue: Service ID 168 type 3; account ID 12
Nov 07 09:07:47 ?Debug : b75e6b70 IPPoolManager: IP 10.18.1.25 is leased from LoginPool '00:0c:42:d3:2d:f1'
Nov 07 09:07:47 ?Debug : b75e6b70 CustomAttrs: custom attributes for SHAPING ID 38 have been added to the reply
Nov 07 09:07:47 ?Debug : b75e6b70 CustomAttrs: custom attributes for NAS ID 6 have been added to the reply
Nov 07 09:07:47 ?Debug : b75e6b70 AcctQueue: lookup: session ID 27 for login '00:0c:42:d3:2d:f1'
Nov 07 09:07:47 ?Debug : b75e6b70 AcctQueue: lookup: session ID 27 for IP 10.18.1.25
Nov 07 09:07:47 ?Debug : b75e6b70 SessionManager: put: session ID 27 timeout scheduled at 1383800927
Nov 07 09:07:47 ?Debug : b75e6b70 SessionManager: put: session ID 27 from NAS 6 OK
Nov 07 09:07:47 ?Debug : b75e6b70 AuthQueue: Reply 
--- RADIUS Pkt ---
  Code: [2]  ID:   [24]
  Auth: Size 16; Data [0xfda17d7d253dd2be780341698ee214af]
    Attr: [6] Vendor: [0] Size 4; Data [0x00000002]
        (Service-Type=INT:2)
    Attr: [7] Vendor: [0] Size 4; Data [0x00000001]
        (Framed-Protocol=INT:1)
    Attr: [8] Vendor: [0] Size 4; Data [0x0a120119]
        (Framed-IP-Address=IP:10.18.1.25)
    Attr: [9] Vendor: [0] Size 4; Data [0xffffffff]
        (Framed-IP-Netmask=IP:255.255.255.255)
    Attr: [27] Vendor: [0] Size 4; Data [0x00015180]
        (Session-Timeout=INT:86400)
    Attr: [4] Vendor: [2352] Size 5; Data [0x6c6f63616c]
        (Redback:Context_Name=STRING:local)
    Attr: [3] Vendor: [2352] Size 4; Data [0x00000001]
        (Redback:DHCP_Max_Leases=INT:1)
    Attr: [104] Vendor: [2352] Size 7; Data [0x646863702d6966]
        (Redback:IP-Interface=STRING:dhcp-if)

Nov 07 09:07:47  ERROR : b74e5b70 RADIUS Packet: to_integer: attr -121 vendor 2352 size != 4
Nov 07 09:07:47  ERROR : b74e5b70 LogicError: attr_type != INTEGER
Nov 07 09:07:47 ?Trace : b74e5b70 trace: Obtained 9 stack frames.
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN3UTM15print_backtraceEv+0x58) [0x8109078]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius() [0x8095f2e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS10to_integerERKNS_7RADAttrE+0xf2) [0x8097712]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9RADPacket4dumpERKS0_+0x84e) [0x809804e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9AcctQueue15packet_receivedEPKcjRKN3UTM6Socket8EndpointE+0xbe) [0x806e75e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9UDPServer3runEv+0x29c) [0x80b234c]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN3UTM6Thread6threadEPv+0x85) [0x8109ea5]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /lib/libpthread.so.0(+0x6a49) [0x750a49]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /lib/libc.so.6(clone+0x5e) [0x4deaae]
Nov 07 09:07:47  ERROR : b74e5b70 RADIUS Packet: to_integer: attr -120 vendor 2352 size != 4
Nov 07 09:07:47  ERROR : b74e5b70 LogicError: attr_type != INTEGER
Nov 07 09:07:47 ?Trace : b74e5b70 trace: Obtained 9 stack frames.
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN3UTM15print_backtraceEv+0x58) [0x8109078]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius() [0x8095f2e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS10to_integerERKNS_7RADAttrE+0xf2) [0x8097712]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9RADPacket4dumpERKS0_+0x84e) [0x809804e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9AcctQueue15packet_receivedEPKcjRKN3UTM6Socket8EndpointE+0xbe) [0x806e75e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9UDPServer3runEv+0x29c) [0x80b234c]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN3UTM6Thread6threadEPv+0x85) [0x8109ea5]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /lib/libpthread.so.0(+0x6a49) [0x750a49]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /lib/libc.so.6(clone+0x5e) [0x4deaae]
Nov 07 09:07:47  ERROR : b74e5b70 RADIUS Packet: to_integer: attr -123 vendor 2352 size != 4
Nov 07 09:07:47  ERROR : b74e5b70 LogicError: attr_type != INTEGER
Nov 07 09:07:47 ?Trace : b74e5b70 trace: Obtained 9 stack frames.
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN3UTM15print_backtraceEv+0x58) [0x8109078]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius() [0x8095f2e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS10to_integerERKNS_7RADAttrE+0xf2) [0x8097712]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9RADPacket4dumpERKS0_+0x84e) [0x809804e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9AcctQueue15packet_receivedEPKcjRKN3UTM6Socket8EndpointE+0xbe) [0x806e75e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9UDPServer3runEv+0x29c) [0x80b234c]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN3UTM6Thread6threadEPv+0x85) [0x8109ea5]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /lib/libpthread.so.0(+0x6a49) [0x750a49]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /lib/libc.so.6(clone+0x5e) [0x4deaae]
Nov 07 09:07:47  ERROR : b74e5b70 RADIUS Packet: to_integer: attr -122 vendor 2352 size != 4
Nov 07 09:07:47  ERROR : b74e5b70 LogicError: attr_type != INTEGER
Nov 07 09:07:47 ?Trace : b74e5b70 trace: Obtained 9 stack frames.
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN3UTM15print_backtraceEv+0x58) [0x8109078]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius() [0x8095f2e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS10to_integerERKNS_7RADAttrE+0xf2) [0x8097712]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9RADPacket4dumpERKS0_+0x84e) [0x809804e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9AcctQueue15packet_receivedEPKcjRKN3UTM6Socket8EndpointE+0xbe) [0x806e75e]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN6RADIUS9UDPServer3runEv+0x29c) [0x80b234c]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /netup/utm5/bin/utm5_radius(_ZN3UTM6Thread6threadEPv+0x85) [0x8109ea5]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /lib/libpthread.so.0(+0x6a49) [0x750a49]
Nov 07 09:07:47 ?Trace : b74e5b70 trace: /lib/libc.so.6(clone+0x5e) [0x4deaae]
Nov 07 09:07:47 ?Debug : b74e5b70 AcctQueue: Request from 10.2.2.250:1812
--- RADIUS Pkt ---
  Code: [4]  ID:   [25]
  Auth: Size 16; Data [0xfa6f35010546784bd422835a84ee529f]
    Attr: [1] Vendor: [0] Size 17; Data [0x30303a30633a34323a64333a32643a6631]
        (User-Name=STRING:00:0c:42:d3:2d:f1)
    Attr: [40] Vendor: [0] Size 4; Data [0x00000002]
        (Acct-Status-Type=INT:2)
    Attr: [44] Vendor: [0] Size 25; Data [0x303130314646464637383030303031392d3532374232343239]
        (Acct-Session-Id=STRING:0101FFFF78000019-527B2429)
    Attr: [6] Vendor: [0] Size 4; Data [0x00000005]
        (Service-Type=INT:5)
    Attr: [32] Vendor: [0] Size 7; Data [0x5265646261636b]
        (NAS-Identifier=STRING:Redback)
    Attr: [5] Vendor: [0] Size 4; Data [0x02020000]
        (NAS-Port=INT:33685504)
    Attr: [62] Vendor: [2352] Size 4; Data [0x02020000]
        (Redback:NAS_Real_Port=INT:33685504)
    Attr: [61] Vendor: [0] Size 4; Data [0x00000005]
        (NAS-Port-Type=INT:5)
    Attr: [87] Vendor: [0] Size 16; Data [0x322f3220636c69707320313331303937]
        (NAS-Port-Id=STRING:2/2 clips 131097)
    Attr: [38] Vendor: [2352] Size 4; Data [0x0000000b]
        (Redback:Medium_Type=INT:11)
    Attr: [-111] Vendor: [2352] Size 17; Data [0x30302d30632d34322d64332d32642d6631]
        (Redback:Mac-Addr=STRING:00-0c-42-d3-2d-f1)
    Attr: [98] Vendor: [2352] Size 4; Data [0x00000004]
        (Redback:Platform-Type=INT:4)
    Attr: [112] Vendor: [2352] Size 7; Data [0x362e352e312e35]
        (Redback:OS-Version=STRING:6.5.1.5)
    Attr: [45] Vendor: [0] Size 4; Data [0x00000001]
        (Acct-Authentic=INT:1)
    Attr: [46] Vendor: [0] Size 4; Data [0x00000000]
        (Acct-Session-Time=INT:0)
    Attr: [49] Vendor: [0] Size 4; Data [0x00000011]
        (Acct-Terminate-Cause=INT:17)
    Attr: [-114] Vendor: [2352] Size 4; Data [0x00000018]
        (Redback:Session_Error_Code=INT:24)
    Attr: [-113] Vendor: [2352] Size 22; Data [0x41757468656e7469636174696f6e206661696c757265]
        (Redback:Session_Error_Msg=STRING:Authentication failure)
    Attr: [47] Vendor: [0] Size 4; Data [0x00000000]
        (Acct-Input-Packets=INT:0)
    Attr: [48] Vendor: [0] Size 4; Data [0x00000000]
        (Acct-Output-Packets=INT:0)
    Attr: [42] Vendor: [0] Size 4; Data [0x00000000]
        (Acct-Input-Octets=INT:0)
    Attr: [43] Vendor: [0] Size 4; Data [0x00000000]
        (Acct-Output-Octets=INT:0)
    Attr: [52] Vendor: [0] Size 4; Data [0x00000000]
        (Acct-Input-Gigawords=INT:0)
    Attr: [53] Vendor: [0] Size 4; Data [0x00000000]
        (Acct-Output-Gigawords=INT:0)
    Attr: [-126] Vendor: [2352] Size 8; Data [0x0000000000000000]
        (Redback:Acct_Input_Packets_64=HEX:...)
    Attr: [-125] Vendor: [2352] Size 8; Data [0x0000000000000000]
        (Redback:Acct_Output_Packets_64=HEX:...)
    Attr: [-128] Vendor: [2352] Size 8; Data [0x0000000000000000]
        (Redback:Acct_Input_Octets_64=HEX:...)
    Attr: [-127] Vendor: [2352] Size 8; Data [0x0000000000000000]
        (Redback:Acct_Output_Octets_64=HEX:...)
    Attr: [-107] Vendor: [2352] Size 4; Data [0x00000000]
        (Redback:Acct-Mcast-In-Packets=INT:0)
    Attr: [-106] Vendor: [2352] Size 4; Data [0x00000000]
        (Redback:Acct-Mcast-Out-Packets=INT:0)
    Attr: [-109] Vendor: [2352] Size 4; Data [0x00000000]
        (Redback:Acct-Mcast-In-Octets=INT:0)
    Attr: [-108] Vendor: [2352] Size 4; Data [0x00000000]
        (Redback:Acct-Mcast-Out-Octets=INT:0)
    Attr: [-121] Vendor: [2352] Size 8; Data [0x0000000000000000]
        (Redback:Acct_Mcast_In_Packets=HEX:...)
    Attr: [-120] Vendor: [2352] Size 8; Data [0x0000000000000000]
        (Redback:Acct_Mcast_Out_Packets=HEX:...)
    Attr: [-123] Vendor: [2352] Size 8; Data [0x0000000000000000]
        (Redback:Acct_Mcast_In_Octets=HEX:...)
    Attr: [-122] Vendor: [2352] Size 8; Data [0x0000000000000000]
        (Redback:Acct_Mcast_Out_Octets=HEX:...)
    Attr: [55] Vendor: [0] Size 4; Data [0x527b2429]
        (Event-Timestamp=DATE:1383801897)

Nov 07 09:07:47 ?Debug : b74e5b70 SessionManager: get: session ID 27
Nov 07 09:07:47 ?Debug : b74e5b70 AcctQueue: found session ID 27 for login '00:0c:42:d3:2d:f1'
Nov 07 09:07:47 ?Debug : b74e5b70 AcctQueue: sid_insert: session ID 27 for SID 0101FFFF78000019-527B2429
Nov 07 09:07:47 ?Debug : b74e5b70 IPPoolManager: IP 10.18.1.25 is released
Nov 07 09:07:47  Info  : b74e5b70 AcctQueue: Accouting-Stop for SID 0101FFFF78000019-527B2429 user '00:0c:42:d3:2d:f1' slink ID 38 from NAS 10.2.2.250
Nov 07 09:07:47 ?Debug : b74e5b70 AcctQueue: lookup: session ID 27 closed
Nov 07 09:07:47 ?Debug : b74e5b70 Transport: sending traffic/dialup session ID 27
Nov 07 09:07:47 ?Debug : b74e5b70 StreamConnection: Sending message ID 0x1107
Nov 07 09:07:47 ?Debug : b74e5b70 SessionManager: put: sessiond ID 27 from NAS 6 is closed
Nov 07 09:07:47 ?Debug : b74e5b70 LoginStorage: Release: login '00:0c:42:d3:2d:f1' used 0 times
Nov 07 09:07:47 ?Debug : b74e5b70 AcctQueue: Reply 
--- RADIUS Pkt ---
  Code: [5]  ID:   [25]
  Auth: Size 16; Data [0xfa6f35010546784bd422835a84ee529f]
 

Может кто мысль подкинет,что может быть не так с аттрибутом ?

[ZeM]

У вас проблема в этом

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

 Attr: [6] Vendor: [0] Size 4; Data [0x00000005] 
         (Service-Type=INT:5)

Железке не нравится это

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

Nov 7 09:13:07: %AAA-7-RAD_ATTR: rad_fill_attr_value: 1/6 Service_Type tag=32 data_type=2 vlen=4 size=6

Могу ошибаться но проблема в том что железка считает тип поля не подходящим для себя исходя из того что ей отдает радиус утм.

[serjk]

2 torrus

Напишите мне на почту, мой ник в домене netup.ru, сообщите, какая сборка биллинга установлена. Сможем сделать фикс.