вот логи при авторизации пользователя
10-20/10:33:23 ERROR [rdsLstnr-p-7-t-8] InetRadiusProcessor - Session ip address not found in packet!
Oct 20 10:39:51.653: %AAA-7-EXCEPT: rad_response_sanity_check: Authenticator check failed. Dropping the request.
Oct 20 10:39:51.653: %AAA-7-EXCEPT: rad_process_received_pkt: Sanity check fail, drop this packet. (00:14:d1:19:9b:0d)
лог BGInetAccessКод:
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] RadiusListenerWorker - REQUEST:
Packet type: Access-Request
Identifier: 6
Authenticator: {85 D2 79 29 66 22 26 54 47 92 ED 6F E6 3B 29 E5}
Attributes:
User-Name=00:14:d1:19:9b:0d
NAS-Port-Id=lg id 25 vlan-id 100 clips 131782
NAS-Identifier=Redback
User-Password=Lw��y.�~�3�����K
NAS-IP-Address=172.16.0.1
NAS-Port=-1761606970
Service-Type=5
NAS-Port-Type=5
Platform-Type=4
Medium-Type=11
Agent-Remote-Id={00 06 AC F1 DF B5 78 A0}
UNKNOWN[2352-202]={3D 3D 07 01 00 14 D1 19 9B 0D}
UNKNOWN[2352-202]={0C 0C 07 75 73 65 72 2D 8F 8A}
Agent-Circuit-Id={00 04 00 64 00 01}
OS-Version=11.1.2.9
Mac-Addr=00-14-d1-19-9b-0d
UNKNOWN[2352-125]={4D 53 46 54 20 35 2E 30}
NAS-Real-Port=788529252
UNKNOWN[3561--1]={02 0A 00 06 AC F1 DF B5 78 A0}
UNKNOWN[3561--1]={01 08 00 04 00 64 00 01}
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetRadiusProcessor - REQUEST_AFTER_PREPROCESS:
Packet type: Access-Request
Identifier: 6
Authenticator: {85 D2 79 29 66 22 26 54 47 92 ED 6F E6 3B 29 E5}
Attributes:
User-Name=0006acf1dfb578a0:000400640001
NAS-Port-Id=lg id 25 vlan-id 100 clips 131782
NAS-Identifier=Redback
User-Password=Lw��y.�~�3�����K
NAS-IP-Address=172.16.0.1
NAS-Port=-1761606970
Service-Type=5
Calling-Station-Id=0014d1199b0d
NAS-Port-Type=5
Platform-Type=4
Medium-Type=11
Agent-Remote-Id={00 06 AC F1 DF B5 78 A0}
UNKNOWN[2352-202]={3D 3D 07 01 00 14 D1 19 9B 0D}
UNKNOWN[2352-202]={0C 0C 07 75 73 65 72 2D 8F 8A}
Agent-Circuit-Id={00 04 00 64 00 01}
OS-Version=11.1.2.9
Mac-Addr=00-14-d1-19-9b-0d
UNKNOWN[2352-125]={4D 53 46 54 20 35 2E 30}
NAS-Real-Port=788529252
UNKNOWN[3561--1]={02 0A 00 06 AC F1 DF B5 78 A0}
UNKNOWN[3561--1]={01 08 00 04 00 64 00 01}
Common options: {agentRemoteId={acf1dfb578a0}, agentCircuitId={000400640001}, deviceState=1}
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetNas - Search by username=0006acf1dfb578a0:000400640001
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetRadiusProcessor - [username=0006acf1dfb578a0:000400640001] Authenticated as inetServId:3
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetNas - Found agentDevice:3
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetApplication - TariffOptionMap: {}
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetApplication - inetServ[id=3] balance ok: 0.00 [0]
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetApplication - OptionSet: []
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetRadiusProcessor - Not found free IP-address in serv
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetRadiusProcessor - Set ip from pool
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetRadiusProcessor - Write new waiting connection to DB
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetRadiusProcessor - New connection id=1409
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetRadiusProcessor - Return code=0
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetRadiusProcessor - RESPONSE_BEFORE_POSTPROCESS:
Packet type: Access-Accept
Identifier: 6
Authenticator: {}
Attributes:
Framed-IP-Address=x.x.x.72
Process time auth: 38
10-20/10:33:58 INFO [rdsLstnr-p-8-t-8] InetRadiusListenerWorker - RESPONSE:
Packet type: Access-Accept
Identifier: 6
Authenticator: {21 81 FE 65 57 C8 92 28 DF 2D F3 4C 5D 14 DD 44}
Attributes:
Process time auth: 44
лог BGInetAccountingКод:
10-20/10:33:23 INFO [rdsLstnr-p-7-t-8] InetRadiusProcessor - REQUEST_AFTER_PREPROCESS:
Packet type: Accounting-Request
Identifier: 109
Authenticator: {ED 87 00 38 48 BF 8E 17 EA 69 48 99 C4 6D 4C 65}
Attributes:
User-Name=0006acf1dfb578a0:000400640001
NAS-Identifier=Redback
NAS-IP-Address=172.16.0.1
NAS-Port=-1761606971
Service-Type=5
Acct-Input-Octets=0
Acct-Output-Octets=0
Acct-Status-Type=2
Acct-Session-Time=0
Acct-Input-Packets=0
Acct-Session-Id=FF160019780002C5-5444AC91
Acct-Authentic=1
NAS-Port-Id=lg id 25 vlan-id 100 clips 131781
Acct-Terminate-Cause=17
Acct-Output-Packets=0
Event-Timestamp=1413786810
Acct-Output-Gigawords=0
Acct-Input-Gigawords=0
Calling-Station-Id=0014d1199b0d
NAS-Port-Type=5
Acct-Mcast-Out-Packets-64={00 00 00 00 00 00 00 00}
Medium-Type=11
Platform-Type=4
Agent-Remote-Id={00 06 AC F1 DF B5 78 A0}
Session-Error-Msg=Authentication failure
Agent-Circuit-Id={00 04 00 64 00 01}
Session-Error-Code=24
Acct-Output-Octets-64={00 00 00 00 00 00 00 00}
Acct-Input-Octets-64={00 00 00 00 00 00 00 00}
Acct-Output-Packets-64={00 00 00 00 00 00 00 00}
Acct-Input-Packets-64={00 00 00 00 00 00 00 00}
Acct-Mcast-In-Octets-64={00 00 00 00 00 00 00 00}
Acct-Mcast-In-Packets-64={00 00 00 00 00 00 00 00}
Acct-Mcast-Out-Octets-64={00 00 00 00 00 00 00 00}
OS-Version=11.1.2.9
Mac-Addr=00-14-d1-19-9b-0d
Acct-Mcast-In-Octets=0
Acct-Mcast-Out-Octets=0
Acct-Mcast-In-Packets=0
NAS-Real-Port=788529252
Acct-Mcast-Out-Packets=0
UNKNOWN[3561--1]={02 0A 00 06 AC F1 DF B5 78 A0}
UNKNOWN[3561--1]={01 08 00 04 00 64 00 01}
Common options: {agentRemoteId={acf1dfb578a0}, agentCircuitId={000400640001}, deviceState=1}
10-20/10:33:23 INFO [rdsLstnr-p-7-t-8] InetNas - Search by username=0006acf1dfb578a0:000400640001
10-20/10:33:23 WARN [rdsLstnr-p-7-t-8] InetRadiusProcessor - Creating NasConnection from accounting packet [statusType=2]
[b]10-20/10:33:23 ERROR [rdsLstnr-p-7-t-8] InetRadiusProcessor - Session ip address not found in packet![/b]
[b]10-20/10:33:23 WARN [rdsLstnr-p-7-t-8] InetNas - NasConnection not found[/b]
10-20/10:33:23 INFO [rdsLstnr-p-7-t-8] RadiusListenerWorker - RESPONSE:
Packet type: Accounting-Response
Identifier: 109
Authenticator: {57 66 93 77 11 9F F0 EB 8E 4F E7 8B 8B DA 02 25}
Attributes:
Process time stop: 8
лог SE100
Код:
[local]Redback#Oct 20 10:39:51.290: [0001]: [255/22:1:26/7/2/711]: %AAA-7-RADIUS: aaa_idx 500002c8: rad_process_time_q: Timer Pop moved 1 req to send q to be retransmitted
Oct 20 10:39:51.291: %AAA-7-RADIUS: rad_send, Process radius requests in authen low priority queue
Oct 20 10:39:51.291: [0001]: [255/22:1:26/7/2/711]: %AAA-7-EXCEPT: aaa_idx 500002c8: rad_abort_send, Abort: timeout
Oct 20 10:39:51.291: %AAA-7-RADIUS: rad_process_send_queue, 1 requests processed (1 retransmit)
Oct 20 10:39:51.291: %AAA-7-RADIUS: rad_mgr, Process radius requests in db response queue
Oct 20 10:39:51.291: [0001]: [255/22:1:26/7/2/711]: %AAA-7-EXCEPT: aaa_idx 500002c8: rad_create_auth_db_reply: Authen/Author request error
Oct 20 10:39:51.291: [0001]: [255/22:1:26/7/2/711]: %AAA-7-EXCEPT: aaa_idx 500002c8: aaa_process_ipc_authen_response: authen method fail for subscriber 00:14:d1:19:9b:0d
Oct 20 10:39:51.291: [0001]: [255/22:1:26/7/2/711]: %AAA-7-RADIUS: aaa_idx 500002c8: rad_process_response: process response to req Authentication. (00:14:d1:19:9b:0d)
Oct 20 10:39:51.291: [0001]: [255/22:1:26/7/2/711]: %AAA-7-RADIUS: aaa_idx 500002c8: rad_free_resource:, Free radius message, rad_idx 1528
Oct 20 10:39:51.291: %AAA-7-RADIUS: aaa_idx 500002c8: rad_clean_aaa_idx_tree: Clean aaa_idx tree for context db_request_type Authentication
Oct 20 10:39:51.291: %AAA-7-RADIUS: rad_mgr, Process radius requests in db request queue
Oct 20 10:39:51.291: [0001]: [255/22:1:26/7/2/711]: %AAA-7-RADIUS: aaa_idx 500002c8: rad_process_aaad_req: Receive request (Accounting Stop)
Oct 20 10:39:51.291: [0001]: [255/22:1:26/7/2/711]: %AAA-7-RADIUS: aaa_idx 500002c8: rad_get_req_event_time: start_time 0x0000004cccee8659 elapsed_time 0 stop_time 0x0000000000000000 event_time_us 0x0000004ccf55313f event_time 0x5444ae37
Oct 20 10:39:51.291: %AAA-7-RADIUS: aaa_idx 500002c8: rad_db_req_type_lookup: Lookup aaa_idx for global db_request_type Accounting Update
Oct 20 10:39:51.291: %AAA-7-RADIUS: aaa_idx 500002c8: rad_db_req_type_lookup: Lookup aaa_idx for context db_request_type Accounting Update
Oct 20 10:39:51.293: %AAA-7-RADIUS: rad_send, Process radius requests in acct low priority queue
Oct 20 10:39:51.293: [0001]: [255/22:1:26/7/2/711]: %AAA-7-RADIUS: aaa_idx 500002c8: do_acct_send: Find free acct server 10.1.1.1 (ctx local src port 1812, dst port 1813). (00:14:d1:19:9b:0d)
Oct 20 10:39:51.293: %AAA-7-RADIUS: User is non nwg user so skipping WIMAX_FORUM_OID attributes
Oct 20 10:39:51.294: [0001]: %AAA-7-RADIUS: Using local address 172.16.0.1
Oct 20 10:39:51.294: [0001]: %AAA-7-RADIUS: do_send: 555 bytes send to radius server 10.1.1.1 (1813).
Oct 20 10:39:51.294: %AAA-7-RADIUS: rad_process_send_queue, 1 requests processed (0 retransmit)
Oct 20 10:39:51.387: %AAA-7-RADIUS: rad_process_received_pkt: Receive 20 bytes from radius server 10.1.1.1 (1813)
Oct 20 10:39:51.387: [0001]: %AAA-7-RADIUS: rad_find_match_srv: Find matching server 10.1.1.1/1813
Oct 20 10:39:51.388: %AAA-7-EXCEPT: rad_response_sanity_check: Authenticator check failed. Dropping the request.
Oct 20 10:39:51.388: %AAA-7-EXCEPT: rad_process_received_pkt: Sanity check fail, drop this packet. (00:14:d1:19:9b:0d)
Oct 20 10:39:51.613: %AAA-7-RADIUS: rad_mgr, Process radius requests in db request queue
Oct 20 10:39:51.614: [0001]: [255/22:1:26/7/2/712]: %AAA-7-RADIUS: aaa_idx 500002c9: rad_process_aaad_req: Receive request (Authentication)
Oct 20 10:39:51.614: [0001]: [255/22:1:26/7/2/712]: %AAA-7-RADIUS: aaa_idx 500002c9: aaaidx_tree_insert: insert aaa_idx to idx tree for context rad_idx 1530 db_request_type Authentication. (00:14:d1:19:9b:0d)
Oct 20 10:39:51.614: %AAA-7-RADIUS: rad_send, Process radius requests in authen low priority queue
Oct 20 10:39:51.614: [0001]: [255/22:1:26/7/2/712]: %AAA-7-RADIUS: aaa_idx 500002c9: do_auth_send: Find free server 10.1.1.1 (ctx local, src port 1812, dst port 1812). (00:14:d1:19:9b:0d)
Oct 20 10:39:51.615: [0001]: %AAA-7-RADIUS: Using local address 172.16.0.1
Oct 20 10:39:51.615: [0001]: %AAA-7-RADIUS: do_send: 314 bytes send to radius server 10.1.1.1 (1812).
Oct 20 10:39:51.615: %AAA-7-RADIUS: rad_process_send_queue, 1 requests processed (0 retransmit)
Oct 20 10:39:51.652: %AAA-7-RADIUS: rad_process_received_pkt: Receive 20 bytes from radius server 10.1.1.1 (1812)
Oct 20 10:39:51.653: [0001]: %AAA-7-RADIUS: rad_find_match_srv: Find matching server 10.1.1.1/1812
Oct 20 10:39:51.653: %AAA-7-EXCEPT: rad_response_sanity_check: Authenticator check failed. Dropping the request.
Oct 20 10:39:51.653: %AAA-7-EXCEPT: rad_process_received_pkt: Sanity check fail, drop this packet. (00:14:d1:19:9b:0d)