Собственно, в продолжение наших наблюдений. Возможно, дубли были связаны элементарно с тем, что мы для разных реалмов не указали idle-timeout сессии. Сейчас он указан - пока наблюдаем. Однако, возникла другая проблема. Если клиент подключен не в дефолтном реалме, то его нельзя кикнуть из монитора, и у меня есть подозрение, что по той же причине не работает check.duplicate, хотя конечно, может быть я не прав. Но, вот что приходит на БРАС, если мы отключаем (обычное отключение, не зависшее) абонента:
Код:
Nov 17 08:24:01.236: RADIUS: POD received from id 6 10.63.9.105:43893, POD Request, len 57
Nov 17 08:24:01.236: Getting session id for NONE(00000378) : db=7FFB3E43FB60
Nov 17 08:24:01.239: AAA/ACCT/NET(00000378): Pick method list 'PPPOE-CLIENTS'
Nov 17 08:24:01.239: AAA/ACCT/SETMLIST(00000378): Handle E6000009, mlist 7FFB3C490DF0, Name PPPOE-CLIENTS
Nov 17 08:24:01.239: AAA/ACCT/EVENT/(00000378): NET DOWN
Nov 17 08:24:01.239: AAA/ACCT/CLIENT(00000378): recv 1410065408bps xmit 1410065408bps
Nov 17 08:24:01.239: AAA/ACCT/HC(00000378): Update PPPoE/F4000378
Nov 17 08:24:01.239: AAA/ACCT/HC(00000378): no HC PPPoE/F4000378
Nov 17 08:24:01.239: AAA/ACCT/NET(00000378): Queueing record is STOP osr 1
Nov 17 08:24:01.239: AAA/ACCT(00000378): del node, session 845
Nov 17 08:24:01.239: AAA/ACCT/NET(00000378): free_rec, count 0
Nov 17 08:24:01.240: AAA/ACCT/EVENT/(00000378): NET DOWN
Nov 17 08:24:01.240: AAA/ACCT(00000378): Accounting method=BG-RADIUS-01 (RADIUS)
Nov 17 08:24:01.240: RADIUS/ENCODE(00000378):Orig. component type = PPPoE
Nov 17 08:24:01.240: RADIUS/ENCODE(00000378): Acct-session-id pre-pended with Nas Port = 0/0/0/3884
Nov 17 08:24:01.240: RADIUS: Format E value 0x34D for character U with bitmask 0xFFFFFFFF
Nov 17 08:24:01.240: RADIUS: Format E port 0x34D with bit 32 processed
Nov 17 08:24:01.240: RADIUS(00000378): Config NAS IP: 10.63.6.250
Nov 17 08:24:01.240: RADIUS(00000378): Config NAS IPv6: ::
Nov 17 08:24:01.240: RADIUS(00000378): Config NAS IP: 10.63.6.250
Nov 17 08:24:01.240: RADIUS(00000378): sending
Nov 17 08:24:01.240: AAA/ACCT/EVENT/(00000378): CALL STOP
Nov 17 08:24:01.240: AAA/ACCT(00000378) reccnt 0, osr 1
Nov 17 08:24:01.240: RADIUS(00000378): Send Accounting-Request to 10.63.9.105:1813 id 1646/11, len 426
Nov 17 08:24:01.240: RADIUS: authenticator CB AB 8F D9 88 A4 C8 2C - 5C FD D4 77 2C 87 14 13
Nov 17 08:24:01.240: RADIUS: Acct-Session-Id [44] 29 "0/0/0/3884_51163FE20000034D"
Nov 17 08:24:01.240: RADIUS: Framed-Protocol [7] 6 PPP [1]
Nov 17 08:24:01.240: RADIUS: Framed-IP-Address [8] 6 77.245.214.251
Nov 17 08:24:01.240: RADIUS: Vendor, Cisco [26] 53
Nov 17 08:24:01.240: RADIUS: Cisco AVpair [1] 47 "ppp-disconnect-cause=Lower Layer disconnected"
Nov 17 08:24:01.240: RADIUS: User-Name [1] 8 "cisco3"
Nov 17 08:24:01.240: RADIUS: Acct-Authentic [45] 6 RADIUS [1]
Nov 17 08:24:01.240: RADIUS: Vendor, Cisco [26] 35
Nov 17 08:24:01.240: RADIUS: Cisco AVpair [1] 29 "connect-progress=LAN Ses Up"
Nov 17 08:24:01.240: RADIUS: Vendor, Cisco [26] 31
Nov 17 08:24:01.240: RADIUS: Cisco AVpair [1] 25 "nas-tx-speed=1410065408"
Nov 17 08:24:01.240: RADIUS: Vendor, Cisco [26] 31
Nov 17 08:24:01.240: RADIUS: Cisco AVpair [1] 25 "nas-rx-speed=1410065408"
Nov 17 08:24:01.240: RADIUS: Acct-Session-Time [46] 6 16
Nov 17 08:24:01.241: RADIUS: Acct-Input-Octets [42] 6 4667
Nov 17 08:24:01.241: RADIUS: Acct-Output-Octets [43] 6 8067
Nov 17 08:24:01.241: RADIUS: Acct-Input-Packets [47] 6 43
Nov 17 08:24:01.241: RADIUS: Acct-Output-Packets [48] 6 35
Nov 17 08:24:01.241: RADIUS: Acct-Terminate-Cause[49] 6 admin-reset [6]
Nov 17 08:24:01.241: RADIUS: Vendor, Cisco [26] 34
Nov 17 08:24:01.241: RADIUS: Cisco AVpair [1] 28 "disc-cause-ext=Radius Disc"
Nov 17 08:24:01.241: RADIUS: Acct-Status-Type [40] 6 Stop [2]
Nov 17 08:24:01.241: RADIUS: Calling-Station-Id [31] 16 "00a0.d1a2.3423"
Nov 17 08:24:01.241: RADIUS: NAS-Port-Type [61] 6 PPPoEoVLAN [33]
Nov 17 08:24:01.241: RADIUS: NAS-Port [5] 6 845
Nov 17 08:24:01.241: RADIUS: NAS-Port-Id [87] 12 "0/0/0/3884"
Nov 17 08:24:01.241: RADIUS: Vendor, Cisco [26] 41
Nov 17 08:24:01.241: RADIUS: Cisco AVpair [1] 35 "client-mac-address=00a0.d1a2.3423"
Nov 17 08:24:01.241: RADIUS: Service-Type [6] 6 Framed [2]
Nov 17 08:24:01.241: RADIUS: NAS-IP-Address [4] 6 10.63.6.250
Nov 17 08:24:01.241: RADIUS: Event-Timestamp [55] 6 1479371041
Nov 17 08:24:01.241: RADIUS: Nas-Identifier [32] 20 "ASR-02.metromax.ru"
Nov 17 08:24:01.241: RADIUS: Acct-Delay-Time [41] 6 0
Nov 17 08:24:01.241: RADIUS(00000378): Sending a IPv4 Radius Packet
Nov 17 08:24:01.241: RADIUS(00000378): Started 5 sec timeout
Nov 17 08:24:01.244: RADIUS: Removing all radius source-int. pointing to Virtual-Access1.1
Nov 17 08:24:01.260: RADIUS: Received from id 1646/11 10.63.9.105:1813, Accounting-response, len 20
Nov 17 08:24:01.260: RADIUS: authenticator 68 00 B5 88 17 EE E4 28 - 96 42 85 9C 76 7E B1 17
Nov 17 08:24:01.260: AAA/ACCT/NET(00000378): STOP protocol reply PASS
Nov 17 08:24:01.260: AAA/ACCT(00000378): Accounting response status = SUCCESS
Nov 17 08:24:01.260: AAA/ACCT(00000378): Send STOP accounting notification to EM successfully
ASR-02#
Nov 17 08:24:01.260: AAA/ACCT/NET(00000378): Cleaning up from Callback osr 0
Nov 17 08:24:01.260: AAA/ACCT/NET(00000378) Record not present
Nov 17 08:24:01.260: /AAA/ACCTNET(00000378) reccnt 0, csr TRUE, osr 0
Nov 17 08:24:01.260: AAA/ACCT/NET(00000378): Last rec in db, intf not enqueued
А вот это при реалмах:
Код:
Nov 17 08:24:55.710: RADIUS: POD received from id 7 10.63.9.105:43893, POD Request, len 61
Nov 17 08:24:55.710: Getting session id for NONE(00000379) : db=7FFB3E425C98
В логах БГ-шки (в аккаунтинге), при условии задействованного не-default реалма, вообще нет намеков на то, что на БРАС отправляется что-то, кроме апдейтов о состоянии сессии. Т.е. ни STOP пакетов, ничего такого.
В чем может быть проблема?