March 2014 - Freeradius-Users - lists.freeradius.org

Re: JSON format to use for rlm_rest
by Werner Stucky 17 Mar '14

17 Mar '14

Thanks Arran the authorization is working now but I can't do more than 1 request. I get a FATAL SIGNAL: Segmentation fault Below my output showing one Request and Response working, and then the following one fails: Ready to process requests rad_recv: Access-Request packet from host 192.168.118.102 port 58368, id=54, length=58 User-Name = '4411' CHAP-Password = 0xcc26c6f1f411c1f53fb28c2c59bb946d81 (0) # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default (0) authorize { (0) [preprocess] = ok (0) chap : Setting 'Auth-Type := CHAP' (0) [chap] = ok (0) [mschap] = noop (0) eap : No EAP-Message, not doing EAP (0) [eap] = noop rlm_rest (rest): Reserved connection (4) (0) rest : Expanding URI components (0) rest : expand: "http://192.168.113.154:2233" -> ' http://192.168.113.154:2233' (0) rest : expand: "/authorize" -> '/authorize' (0) rest : Sending HTTP POST to "http://192.168.113.154:2233/authorize" (0) rest : Encoding attribute "User-Name" (0) rest : Encoding attribute "CHAP-Password" (0) rest : Encoding attribute "NAS-IP-Address" (0) rest : Encoding attribute "CHAP-Challenge" (0) rest : Status : 200 (OK) (0) rest : Type : json (application/json) (0) rest : Parsing attribute "control:Cleartext-Password" (0) rest : expand: "12345" -> '12345' (0) rest : Parsing attribute "reply:Reply-Message" (0) rest : expand: "pass" -> 'pass' rlm_rest (rest): Released connection (4) (0) [rest] = updated (0) [expiration] = noop (0) [logintime] = noop (0) WARNING: pap : Auth-Type already set. Not setting to PAP (0) [pap] = noop (0) } # authorize = updated (0) Found Auth-Type = CHAP (0) # Executing group from file /usr/local/etc/raddb/sites-enabled/default (0) Auth-Type CHAP { (0) chap : Login attempt by "4411" with CHAP password (0) chap : Comparing with "known good" Cleartext-Password (0) chap : CHAP user "4411" authenticated successfully (0) [chap] = ok (0) } # Auth-Type CHAP = ok (0) # Executing section post-auth from file /usr/local/etc/raddb/sites-enabled/default (0) post-auth { (0) [exec] = noop (0) remove_reply_message_if_eap remove_reply_message_if_eap { (0) ? if (reply:EAP-Message && reply:Reply-Message) (0) ? if (reply:EAP-Message && reply:Reply-Message) -> FALSE (0) else else { (0) [noop] = noop (0) } # else else = noop (0) } # remove_reply_message_if_eap remove_reply_message_if_eap = noop (0) } # post-auth = noop Sending Access-Accept of id 54 from 192.168.113.151 port 1812 to 192.168.118.102 port 58368 Reply-Message = 'pass' (0) Finished request 0. rad_recv: Access-Request packet from host 192.168.118.102 port 58368, id=54, length=58 Sending Access-Accept of id 54 from 192.168.113.151 port 1812 to 192.168.118.102 port 58368 Waking up in 999997.9 seconds. rad_recv: Access-Request packet from host 192.168.118.102 port 58368, id=54, length=58 Sending Access-Accept of id 54 from 192.168.113.151 port 1812 to 192.168.118.102 port 58368 Waking up in 1999995.9 seconds. rad_recv: Access-Request packet from host 192.168.118.102 port 45385, id=55, length=58 User-Name = '4411' CHAP-Password = 0x72932b75282b7002a540446a3f6929a94b (1) # Executing section authorize from file /usr/local/etc/raddb/sites-enabled/default (1) authorize { (1) [preprocess] = ok (1) chap : Setting 'Auth-Type := CHAP' (1) [chap] = ok (1) [mschap] = noop (1) eap : No EAP-Message, not doing EAP (1) [eap] = noop rlm_rest (rest): Reserved connection (4) (1) rest : Expanding URI components (1) rest : expand: "http://192.168.113.154:2233" -> ' http://192.168.113.154:2233' (1) rest : expand: "/authorize" -> '/authorize' (1) rest : Sending HTTP POST to "http://192.168.113.154:2233/authorize" (1) rest : Encoding attribute "User-Name" (1) rest : Encoding attribute "CHAP-Password" (1) rest : Encoding attribute "NAS-IP-Address" (1) rest : Encoding attribute "CHAP-Challenge" (1) rest : Status : 200 (OK) (1) rest : Type : json (application/json) (1) rest : Parsing attribute "control:Cleartext-Password" (1) rest : expand: "12345" -> '12345' FATAL SIGNAL: Segmentation fault Backtrace of last 25 frames: /usr/local/lib/libfreeradius-radius.so(fr_fault+0x72) [0x7effd806b92a] /lib64/libpthread.so.0() [0x36a200f710] /usr/local/lib/libjson-c.so.2(array_list_get_idx+0xd) [0x7effc89c2dcd] /usr/local/lib/rlm_rest.so(+0x5952) [0x7effc8bcf952] /usr/local/lib/rlm_rest.so(+0x5a99) [0x7effc8bcfa99] /usr/local/lib/rlm_rest.so(rest_request_decode+0x14e) [0x7effc8bd15a3] /usr/local/lib/rlm_rest.so(+0x337f) [0x7effc8bcd37f] radiusd() [0x422e71] radiusd() [0x42368b] radiusd() [0x423047] radiusd() [0x4243fe] radiusd(modcall+0x88) [0x425140] radiusd(indexed_modcall+0x2e4) [0x420723] radiusd(process_authorize+0x22) [0x422741] radiusd(rad_authenticate+0x1af) [0x40d241] radiusd() [0x4335af] radiusd() [0x4326bd] radiusd(request_receive+0x5f4) [0x433c69] radiusd() [0x414588] radiusd() [0x439523] /usr/local/lib/libfreeradius-radius.so(fr_event_loop+0x5ba) [0x7effd808edeb] radiusd(radius_event_process+0x26) [0x43ac73] radiusd(main+0xbdf) [0x4285ae] /lib64/libc.so.6(__libc_start_main+0xfd) [0x36a1c1ed1d] radiusd() [0x40c449] No panic action set _EXIT CALLED src/lib/debug.c[262]: 1:

2 1

Freeradius-mysql authentication
by Dahoun Baya 17 Mar '14

17 Mar '14

Hi, I am curently student, I have to implement freeradius-mysql with 802.1x and test this with client so the client must autheticate before entering to LAN. So, for the test it works only when I use client who belongs to users file (radius file) when I try with sql's client (client which belongs to my database) that doesn't work???????I get no response Can anyone help me please?? Thanks

5 8

Re: JSON format to use for rlm_rest
by Werner Stucky 17 Mar '14

17 Mar '14

Hi Arran Not compiling on my side : CC src/modules/rlm_rest/rest.c src/modules/rlm_rest/rest.c: In function 'rest_write_header': src/modules/rlm_rest/rest.c:1465: error: invalid operands to binary - (have 'long unsigned int' and 'const char *') make: *** [build/objs/src/modules/rlm_rest/rest.lo] Error 1 I'm also not sure how to specify if the vp should be for control or reply as in the Perl server example : $resp->header("Content-Type" => "application/x-www-form-urlencoded"); $resp->content("control:Cleartext-Password=password&reply:Reply-Message=testing123"); Here the control and reply are separate. Am I understanding this correctly? Thanks for the help.

2 1

JSON format to use for rlm_rest
by Werner Stucky 16 Mar '14

16 Mar '14

Hi Guys I am struggling to find the right format to respond with to the rlm_rest module in JSON format. I'm responding with : { "ClearText-Password": "12345", "Reply-Message": "pass" } I'm responding with the correct Content-Type of "application/json" I'm getting to following error: (10) rest : Processing body (10) rest : Decoding attribute "ClearText-Password" (10) WARNING: rest : Attribute name refers to outer request but not in a tunnel, skipping (10) rest : Decoding attribute "Reply-Message" (10) WARNING: rest : Attribute name refers to outer request but not in a tunnel, skipping Could someone please point me in the right direction. I've tried many different ways of formating the JSON. Thank you

2 2

regarding cisco dhcp relay agent-generation of circuit id for authentication
by Mahima Kumar 15 Mar '14

15 Mar '14

Please take a look at my config or please Can anyone please post here configuration of a cisco router or switch acting as a dhcp relay agent which generates circuit id and based on this circuit id i can get my client authenticated from the freeradius server and client gets ip address from dhcp server (i have a server which is working fine). I have tried all the possible configs online , but i am unable to get the circuit id. *Cisco 2900 as relay agent configuration :* Scenario 1 ) client --- cisco router relay agent ---- radius and dhcp server Router#sh run Building configuration... Current configuration : 1330 bytes ! version 12.4 service timestamps debug datetime msec service timestamps log datetime msec no service password-encryption ! hostname Router ! boot-start-marker boot-end-marker ! ! no aaa new-model memory-size iomem 10 ! ! ip cef ip dhcp relay information option no ip dhcp relay information check ip dhcp relay information trust-all ! ! multilink bundle-name authenticated ! ! voice-card 0 no dspfarm ! ! vlan internal allocation policy ascending ! interface GigabitEthernet0/0 ip dhcp relay information option-insert ip address 192.168.1.1 255.255.255.0 ip helper-address 10.3.31.40 ip directed-broadcast duplex auto speed auto ! interface GigabitEthernet0/1 ip address 10.3.31.250 255.255.255.0 ip directed-broadcast duplex auto speed auto ! interface Serial0/0/0 no ip address shutdown no fair-queue clock rate 2000000 ! interface Serial0/0/1 no ip address shutdown clock rate 2000000 ! interface Vlan1 no ip address ! ip route 0.0.0.0 0.0.0.0 GigabitEthernet0/1 ! ip http server no ip http secure-server ! control-plane ! ! line con 0 line aux 0 line vty 0 4 login ! scheduler allocate 20000 1000 ! end Router# *DEBUG OUTPUT * *Mar 14 22:56:53.233: DHCPD: Sending notification of DISCOVER: *Mar 14 22:56:53.233: DHCPD: htype 1 chaddr 0017.e069.24c0 *Mar 14 22:56:53.233: DHCPD: remote id 020a0000c0a8010100000000 *Mar 14 22:56:53.233: DHCPD: circuit id 00000000 *Mar 14 22:56:53.233: DHCPD: setting giaddr to 192.168.1.1. *Mar 14 22:56:53.233: DHCPD: adding relay information option. *Mar 14 22:56:53.233: DHCPD: BOOTREQUEST from 0063.6973.636f.2d30.3031.372e.6530.3639.2e32.3463 .302d.4769.302f.30 forwarded to 10.3.31.40. *Mar 14 22:56:56.765: DHCPD: Sending notification of DISCOVER: *Mar 14 22:56:56.765: DHCPD: htype 1 chaddr 0017.e069.24c0 *Mar 14 22:56:56.765: DHCPD: remote id 020a0000c0a8010100000000 *Mar 14 22:56:56.765: DHCPD: circuit id 00000000 *Mar 14 22:56:56.765: DHCPD: setting giaddr to 192.168.1.1. *Mar 14 22:56:56.765: DHCPD: adding relay information option. *Mar 14 22:56:56.765: DHCPD: BOOTREQUEST from 0063.6973.636f.2d30.3031.372e.6530.3639.2e32.3463 .302d.4769.302f.30 forwarded to 10.3.31.40. *Mar 14 22:56:56.785: DHCP: XID did NOT MATCH in dhcpc_for_us() *Mar 14 22:56:56.785: DHCPD: forwarding BOOTREPLY to client 0017.e069.24c0. *Mar 14 22:56:56.785: DHCPD: No vpn from sub-option, using global *Mar 14 22:56:56.785: DHCPD: Setting giaddr to 192.168.1.1 *Mar 14 22:56:56.785: DHCPD: Forwarding reply on numbered intf *Mar 14 22:56:56.785: DHCPD: relay information option is removed *Mar 14 22:56:56.785: DHCPD: broadcasting BOOTREPLY to client 0017.e069.24c0. *Mar 14 22:56:56.789: DHCPD: Finding a relay for client 0063.6973.636f.2d30.3031.372e.6530.3639 .2e32.3463.302d.4769.302f.30 on interface GigabitEthernet0/0. *Mar 14 22:56:56.789: DHCPD: Seeing if there is an internally specified pool class: *Mar 14 22:56:56.789: DHCPD: htype 1 chaddr 0017.e069.24c0 *Mar 14 22:56:56.789: DHCPD: remote id 020a0000c0a8010100000000 *Mar 14 22:56:56.789: DHCPD: circuit id 00000000 *Mar 14 22:56:56.789: DHCPD: there is no pool for 192.168.1.1. *Mar 14 22:56:56.789: DHCPD: setting giaddr to 192.168.1.1. *Mar 14 22:56:56.789: DHCPD: adding relay information option. *Mar 14 22:56:56.789: DHCPD: BOOTREQUEST from 0063.6973.636f.2d30.3031.372e.6530.3639.2e32.3463 .302d.4769.302f.30 forwarded to 10.3.31.40. *Mar 14 22:56:56.805: DHCP: XID did NOT MATCH in dhcpc_for_us() *Mar 14 22:56:56.805: DHCPD: forwarding BOOTREPLY to client 0017.e069.24c0. *Mar 14 22:56:56.805: DHCPD: No vpn from sub-option, using global *Mar 14 22:56:56.805: DHCPD: Setting giaddr to 192.168.1.1 *Mar 14 22:56:56.805: DHCPD: Forwarding reply on numbered intf *Mar 14 22:56:56.805: DHCPD: relay information option is removed *Mar 14 22:56:56.805: DHCPD: broadcasting BOOTREPLY to client 0017.e069.24c0. PROBLEM : the circuit id value is 0. I need a circuit id to authenticate client from radius server. my client is getting ip address from the dhcp server, and there is end to end connectivity from client---cisco relay agent --- radius and dhcp server . But i need to authenticate my client based on the circuit id generated by the relay agent. *Scenario 2) Switch 3750 as relay agent * client --- switch as relay agent --- radius and dhcp server Switch#sh run Building configuration... Current configuration : 1869 bytes ! version 12.2 no service pad service timestamps debug datetime msec service timestamps log datetime msec no service password-encryption ! hostname Switch ! boot-start-marker boot-end-marker ! ! no aaa new-model switch 1 provision ws-c3750g-24ps system mtu routing 1600 ip subnet-zero ip routing ip dhcp relay information option no ip dhcp relay information check ip dhcp relay information trust-all ! spanning-tree mode pvst spanning-tree etherchannel guard misconfig spanning-tree extend system-id ! vlan internal allocation policy ascending ! interface GigabitEthernet1/0/1 switchport access vlan 5 switchport mode access ! interface GigabitEthernet1/0/2 ! interface GigabitEthernet1/0/3 no switchport ip address 10.3.31.250 255.255.255.0 ! interface GigabitEthernet1/0/4 ! ! interface Vlan1 no ip address shutdown ! interface Vlan5 ip dhcp relay information option-insert ip address 192.168.1.1 255.255.255.0 ip helper-address 10.3.31.40 ! ip classless ip http server ! control-plane ! ! line con 0 line vty 5 15 ! end Switch# *DEBUG OUTPUT: * *Mar 1 00:36:39.073: DHCPD: interface Vlan5 coming up *Mar 1 00:36:40.382: DHCPD: Reload workspace interface Vlan5 tableid 0. *Mar 1 00:36:40.382: DHCPD: tableid for 192.168.1.1 on Vlan5 is 0 *Mar 1 00:36:40.382: DHCPD: client's VPN is . *Mar 1 00:36:40.382: DHCPD: Sending notification of DISCOVER: *Mar 1 00:36:40.382: DHCPD: htype 1 chaddr 0017.e069.24c0 *Mar 1 00:36:40.382: DHCPD: remote id 020a0000c0a8010105000000 *Mar 1 00:36:40.382: DHCPD: interface = Vlan5 *Mar 1 00:36:40.382: DHCPD: Looking up binding using address 192.168.1.1 *Mar 1 00:36:40.382: DHCPD: setting giaddr to 192.168.1.1. *Mar 1 00:36:40.382: DHCPD: adding relay information option. *Mar 1 00:36:40.382: DHCPD: BOOTREQUEST from 0063.6973.636f.2d30.3031.372e.6530.3639.2e32.3463.302d.4769.302f.30 forwarded to 10.3.31.40. *Mar 1 00:36:40.407: DHCP: XID did NOT MATCH in dhcpc_for_us() *Mar 1 00:36:40.407: DHCPD: Reload workspace interface GigabitEthernet1/0/3 tableid 0. *Mar 1 00:36:40.407: DHCPD: tableid for 10.3.31.250 on GigabitEthernet1/0/3 is 0 *Mar 1 00:36:40.407: DHCPD: client's VPN is . *Mar 1 00:36:40.407: DHCPD: DHCPOFFER notify setup address 192.168.1.5 mask 255.255.255.0 *Mar 1 00:36:40.407: DHCPD: forwarding BOOTREPLY to client 0017.e069.24c0. *Mar 1 00:36:40.407: DHCPD: Forwarding reply on numbered intf *Mar 1 00:36:40.407: DHCPD: Option82 is currently: *Mar 1 00:36:40.407: 0109312f312f313a31303002157375622d70726f662d312d736c612d70726f662d31 *Mar 1 00:36:40.407: DHCPD: Removing option82 information *Mar 1 00:36:40.407: DHCPD: relay information option removed *Mar 1 00:36:40.407: DHCPD: Option82 is removed *Mar 1 00:36:40.407: DHCPD: broadcasting BOOTREPLY to client 0017.e069.24c0. *Mar 1 00:36:40.407: DHCPD: Reload workspace interface Vlan5 tableid 0. *Mar 1 00:36:40.407: DHCPD: tableid for 192.168.1.1 on Vlan5 is 0 *Mar 1 00:36:40.407: DHCPD: client's VPN is . *Mar 1 00:36:40.407: DHCPD: Finding a relay for client 0063.6973.636f.2d30.3031.372e.6530.3639.2e32.3463.302d.4769.302f.30 on interface Vlan5. *Mar 1 00:36:40.407: DHCPD: there is no pool for 192.168.1.1. *Mar 1 00:36:40.407: DHCPD: Looking up binding using address 192.168.1.1 *Mar 1 00:36:40.407: DHCPD: setting giaddr to 192.168.1.1. *Mar 1 00:36:40.407: DHCPD: adding relay information option. *Mar 1 00:36:40.407: DHCPD: BOOTREQUEST from 0063.6973.636f.2d30.3031.372e.6530.3639.2e32.3463.302d.4769.302f.30 forwarded to 10.3.31.40. *Mar 1 00:36:40.424: DHCP: XID did NOT MATCH in dhcpc_for_us() *Mar 1 00:36:40.424: DHCPD: Reload workspace interface GigabitEthernet1/0/3 tableid 0. *Mar 1 00:36:40.424: DHCPD: tableid for 10.3.31.250 on GigabitEthernet1/0/3 is 0 *Mar 1 00:36:40.424: DHCPD: client's VPN is . *Mar 1 00:36:40.424: DHCPD: forwarding BOOTREPLY to client 0017.e069.24c0. *Mar 1 00:36:40.424: DHCPD: Forwarding reply on numbered intf *Mar 1 00:36:40.424: DHCPD: Option82 is currently: *Mar 1 00:36:40.424: 0109312f312f313a31303002157375622d70726f662d312d736c612d70726f662d31 *Mar 1 00:36:40.424: DHCPD: Removing option82 information *Mar 1 00:36:40.424: DHCPD: relay information option removed *Mar 1 00:36:40.424: DHCPD: Option82 is removed *Mar 1 00:36:40.424: DHCPD: broadcasting BOOTREPLY to client 0017.e069.24c0. *PROBLEM : No circuit id generation which is required by me.* Regards, Mahima Kumar 1365962

2 1

Issue with DHCP with Wireless card
by Hugh McLenaghan 14 Mar '14

14 Mar '14

Hi, I've been struggling getting the DHCP server to work. I have the configuration correct. The server starts fine, it listens on the port. When a client sends a request, the request is received correctly. it's then processed as expected, and it supposedly sends out the reply. However I DO NOT see the reply on the interface when i do a tcpdump. I've tried changing multiple settings including setting broadcast off/on, I've changed the IP address bindings and that doesn't help. I've also tried using different versions of freeradius, from 2.2.x through 3.0.1 and 3.1.0 (the git repository). I know the wireless card will support it, because when i try using the regular ISC DHCPD daemon, it gets an IP address and I can see the request/reply packets. One thing I did notice though in the debug logs of freeradius, is when it supposedly sends the reply packet the source/from address is 0.0.0.0:67 however when ISC DHCPD sends it's response it comes from the IP address of the interface itself e.g. 10.199.100.1:67. I've been fighting with this for days now. I've also tried using ISC-DHCP-relay server to relay the requests from the client WLAN0 interface directly to the DHCP server. It sends the request to the server, which supposedly sends the reply back to it, however again, the response is never seen by the relay server. I'm wondering if somehow the dhcp part of the radius server isn't able to send the packets back, whether it's set up to use a gateway DHCP relay or not. Not sure how to troubleshoot this further. Has anyone else had this issue? For my tests i've been using a raspberry Pi running raspian OS. I'm going to try to do this on a regular VM tomorrow to see if it's related to the OS/hardware. Hugh

5 25

Reject Mac Address
by Khapare Joshi 14 Mar '14

14 Mar '14

Hello all, I am running freeradius-2.1 - users authentication against AD (works well). I have list of MAC address which I want to reject. So the question is how do I tell freeradius server to the file rejectmacaddres.txt file which has banned mac addresses ? Thanks, K

2 2

FreeRadius MSCHAP Authenticatiom With Realm Fails
by T I 14 Mar '14

14 Mar '14

Hi I have FreeRadius 2.0 server with AD as user store. Authenticating using EAP-PEAP-MSCHAP2.Local realm is defined in proxy.conf. Authentications works fine without realm added to the username. As soon as I authenticate using username with realm, i.e. username(a)realm.com, authentication fails. The reason for failure is clear, it fails because radius server mschap module creates challenge hash with username which includes realm. I need the radius mschap module to create hash from stripped username, i.e. which doesn't include the realm. Any ideas? Please see radius debug log below: rad_recv: Access-Request packet from host 192.168.1.254 port 55769, id=138, length=376 Service-Type = Framed-User Framed-MTU = 1400 User-Name = "user5(a)igor.local" State = 0x3ce95d9d3fed44ec4019661d07f2a324 NAS-Port-Id = "wlan1" NAS-Port-Type = Wireless-802.11 Calling-Station-Id = "5C-3C-27-29-AE-0B" Called-Station-Id = "D4-CA-6D-A6-53-7B:eduroam test" EAP-Message = 0x020400d01980000000c61603010086100000820080791cc56766422be7f48414f5942dda519afd607aea2fae890f9236e8af61cf71c66f4f80a5d427672d7f949a3fa163b959f0f1957f382f533a3f9c23d576dafcb5d36ca04dc7d0002203513a23b9394b75cf98f241a6c585583593f6622829a39a736160f0f83b567fa7bbc253558191630071d1889827f6118f366040f69d8814030100010116030100307173492977a9f772a302c0ecb7d2612700f9433dce8e08ff0e74b84dbc62de5fe5a95921f364f8c68dd38484550022ae Message-Authenticator = 0x9a61469cb26792ebd980f294bd9a64c9 NAS-Identifier = "MikroTik" NAS-IP-Address = 192.168.1.254 # Executing section authorize from file /etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] expand: %t -> Thu Mar 13 16:17:17 2014 ++[auth_log] returns ok ++[mschap] returns noop [suffix] Looking up realm "igor.local" for User-Name = "user5(a)igor.local" [suffix] Found realm "igor.local" [suffix] Adding Stripped-User-Name = "user5" [suffix] Adding Realm = "igor.local" [suffix] Authentication realm is LOCAL. ++[suffix] returns ok [eap] EAP packet type response id 4 length 208 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS TLS Length 198 [peap] Length Included [peap] eaptls_verify returned 11 [peap] <<< TLS 1.0 Handshake [length 0086], ClientKeyExchange [peap] TLS_accept: SSLv3 read client key exchange A [peap] <<< TLS 1.0 ChangeCipherSpec [length 0001] [peap] <<< TLS 1.0 Handshake [length 0010], Finished [peap] TLS_accept: SSLv3 read finished A [peap] >>> TLS 1.0 ChangeCipherSpec [length 0001] [peap] TLS_accept: SSLv3 write change cipher spec A [peap] >>> TLS 1.0 Handshake [length 0010], Finished [peap] TLS_accept: SSLv3 write finished A [peap] TLS_accept: SSLv3 flush data [peap] (other): SSL negotiation finished successfully SSL Connection Established [peap] eaptls_process returned 13 [peap] EAPTLS_HANDLED ++[eap] returns handled Sending Access-Challenge of id 138 to 192.168.1.254 port 55769 EAP-Message = 0x0105004119001403010001011603010030b0496fb6db5990b1f6a9cf2425dd8587e12b8f4e394f4bd8ccd2c077fe9ba54077bc170e9af421dda6e1deba0235ae8b Message-Authenticator = 0x00000000000000000000000000000000 State = 0x3ce95d9d38ec44ec4019661d07f2a324 Finished request 4. Going to the next request Waking up in 4.8 seconds. rad_recv: Access-Request packet from host 192.168.1.254 port 56590, id=139, length=174 Service-Type = Framed-User Framed-MTU = 1400 User-Name = "user5(a)igor.local" State = 0x3ce95d9d38ec44ec4019661d07f2a324 NAS-Port-Id = "wlan1" NAS-Port-Type = Wireless-802.11 Calling-Station-Id = "5C-3C-27-29-AE-0B" Called-Station-Id = "D4-CA-6D-A6-53-7B:eduroam test" EAP-Message = 0x020500061900 Message-Authenticator = 0xd034f4a268e3ac260f43575b92006929 NAS-Identifier = "MikroTik" NAS-IP-Address = 192.168.1.254 # Executing section authorize from file /etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] expand: %t -> Thu Mar 13 16:17:17 2014 ++[auth_log] returns ok ++[mschap] returns noop [suffix] Looking up realm "igor.local" for User-Name = "user5(a)igor.local" [suffix] Found realm "igor.local" [suffix] Adding Stripped-User-Name = "user5" [suffix] Adding Realm = "igor.local" [suffix] Authentication realm is LOCAL. ++[suffix] returns ok [eap] EAP packet type response id 5 length 6 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS [peap] Received TLS ACK [peap] ACK handshake is finished [peap] eaptls_verify returned 3 [peap] eaptls_process returned 3 [peap] EAPTLS_SUCCESS [peap] Session established. Decoding tunneled attributes. [peap] Peap state TUNNEL ESTABLISHED ++[eap] returns handled Sending Access-Challenge of id 139 to 192.168.1.254 port 56590 EAP-Message = 0x0106002b190017030100205c4bae935a0f79fa0d08a9ce246433654e7edd61806e1f065cc4bdc32958e161 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x3ce95d9d39ef44ec4019661d07f2a324 Finished request 5. Going to the next request Waking up in 4.8 seconds. rad_recv: Access-Request packet from host 192.168.1.254 port 53256, id=140, length=264 Service-Type = Framed-User Framed-MTU = 1400 User-Name = "user5(a)igor.local" State = 0x3ce95d9d39ef44ec4019661d07f2a324 NAS-Port-Id = "wlan1" NAS-Port-Type = Wireless-802.11 Calling-Station-Id = "5C-3C-27-29-AE-0B" Called-Station-Id = "D4-CA-6D-A6-53-7B:eduroam test" EAP-Message = 0x02060060190017030100209cc7c674b7a342e6bec99adea0f61331d5f99b0f6a5950927de5ae55a0bcbcbd1703010030a3f8d19a2da43ad4bee38bdadeeb8049f2ccd3b6452763ed52490a8ff701f7dddeb0d19ec99dc82884df40d0c650c45c Message-Authenticator = 0x4c2f97f2d3325123f0616a879cb73a5f NAS-Identifier = "MikroTik" NAS-IP-Address = 192.168.1.254 # Executing section authorize from file /etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] expand: %t -> Thu Mar 13 16:17:17 2014 ++[auth_log] returns ok ++[mschap] returns noop [suffix] Looking up realm "igor.local" for User-Name = "user5(a)igor.local" [suffix] Found realm "igor.local" [suffix] Adding Stripped-User-Name = "user5" [suffix] Adding Realm = "igor.local" [suffix] Authentication realm is LOCAL. ++[suffix] returns ok [eap] EAP packet type response id 6 length 96 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS [peap] eaptls_verify returned 7 [peap] Done initial handshake [peap] eaptls_process returned 7 [peap] EAPTLS_OK [peap] Session established. Decoding tunneled attributes. [peap] Peap state WAITING FOR INNER IDENTITY [peap] Identity - user5(a)igor.local [peap] Got inner identity 'user5(a)igor.local' [peap] Setting default EAP type for tunneled EAP session. [peap] Got tunneled request EAP-Message = 0x020600150175736572354069676f722e6c6f63616c server { [peap] Setting User-Name to user5(a)igor.local Sending tunneled request EAP-Message = 0x020600150175736572354069676f722e6c6f63616c FreeRADIUS-Proxied-To = 127.0.0.1 User-Name = "user5(a)igor.local" Service-Type = Framed-User Framed-MTU = 1400 NAS-Port-Id = "wlan1" NAS-Port-Type = Wireless-802.11 Calling-Station-Id = "5C-3C-27-29-AE-0B" Called-Station-Id = "D4-CA-6D-A6-53-7B:eduroam test" NAS-Identifier = "MikroTik" NAS-IP-Address = 192.168.1.254 server inner-tunnel { # Executing section authorize from file /etc/raddb/sites-enabled/inner-tunnel +- entering group authorize {...} [auth_log] expand: /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] expand: %t -> Thu Mar 13 16:17:17 2014 ++[auth_log] returns ok [eap] EAP packet type response id 6 length 21 [eap] No EAP Start, assuming it's an on-going EAP conversation ++[eap] returns updated ++[files] returns noop ++[mschap] returns noop [pap] WARNING! No "known good" password found for the user. Authentication may fail because of this. ++[pap] returns noop Found Auth-Type = EAP # Executing group from file /etc/raddb/sites-enabled/inner-tunnel +- entering group authenticate {...} [eap] EAP Identity [eap] processing type mschapv2 rlm_eap_mschapv2: Issuing Challenge ++[eap] returns handled } # server inner-tunnel [peap] Got tunneled reply code 11 EAP-Message = 0x0107002a1a010700251028ee58cd731c7f9fdd4de2f82cc1bf8e75736572354069676f722e6c6f63616c Message-Authenticator = 0x00000000000000000000000000000000 State = 0x6d2798eb6d20828b51434e222e2c4892 [peap] Got tunneled reply RADIUS code 11 EAP-Message = 0x0107002a1a010700251028ee58cd731c7f9fdd4de2f82cc1bf8e75736572354069676f722e6c6f63616c Message-Authenticator = 0x00000000000000000000000000000000 State = 0x6d2798eb6d20828b51434e222e2c4892 [peap] Got tunneled Access-Challenge ++[eap] returns handled Sending Access-Challenge of id 140 to 192.168.1.254 port 53256 EAP-Message = 0x0107004b190017030100400140898f0ff025ef80db345ef75a51b4a94d6b114a1d16ef08e1fc4c2d9de5d9895bd09efcdbc614e3886fa835507c92e6b34d2d657247692685e84c0e1f540f Message-Authenticator = 0x00000000000000000000000000000000 State = 0x3ce95d9d3aee44ec4019661d07f2a324 Finished request 6. Going to the next request Waking up in 4.8 seconds. rad_recv: Access-Request packet from host 192.168.1.254 port 47603, id=141, length=312 Service-Type = Framed-User Framed-MTU = 1400 User-Name = "user5(a)igor.local" State = 0x3ce95d9d3aee44ec4019661d07f2a324 NAS-Port-Id = "wlan1" NAS-Port-Type = Wireless-802.11 Calling-Station-Id = "5C-3C-27-29-AE-0B" Called-Station-Id = "D4-CA-6D-A6-53-7B:eduroam test" EAP-Message = 0x020700901900170301002045693fe624f18512a0f9c83b0b896669c3a55066d0d54494bdee159a241df7cf170301006081573695ccc8dd51297f737d1ab47a212c08801a46c831418959b106028089aac0d7cec2f5aa0740909f7ae40d4274bd4534fa24cc41b9c775c3202ad337b19d7b7062c980579e7d31ca54439954aedcb279acd9cc65df2c1fdb85039fb26142 Message-Authenticator = 0x97fd332a0965353e5459137c179a2c62 NAS-Identifier = "MikroTik" NAS-IP-Address = 192.168.1.254 # Executing section authorize from file /etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] expand: %t -> Thu Mar 13 16:17:17 2014 ++[auth_log] returns ok ++[mschap] returns noop [suffix] Looking up realm "igor.local" for User-Name = "user5(a)igor.local" [suffix] Found realm "igor.local" [suffix] Adding Stripped-User-Name = "user5" [suffix] Adding Realm = "igor.local" [suffix] Authentication realm is LOCAL. ++[suffix] returns ok [eap] EAP packet type response id 7 length 144 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS [peap] eaptls_verify returned 7 [peap] Done initial handshake [peap] eaptls_process returned 7 [peap] EAPTLS_OK [peap] Session established. Decoding tunneled attributes. [peap] Peap state phase2 [peap] EAP type mschapv2 [peap] Got tunneled request EAP-Message = 0x0207004b1a02070046317c71750309db8428b4ec154f7e6c5b840000000000000000a333f4999fd9698891d2fbc675892007431387796abfa13b0075736572354069676f722e6c6f63616c server { [peap] Setting User-Name to user5(a)igor.local Sending tunneled request EAP-Message = 0x0207004b1a02070046317c71750309db8428b4ec154f7e6c5b840000000000000000a333f4999fd9698891d2fbc675892007431387796abfa13b0075736572354069676f722e6c6f63616c FreeRADIUS-Proxied-To = 127.0.0.1 User-Name = "user5(a)igor.local" State = 0x6d2798eb6d20828b51434e222e2c4892 Service-Type = Framed-User Framed-MTU = 1400 NAS-Port-Id = "wlan1" NAS-Port-Type = Wireless-802.11 Calling-Station-Id = "5C-3C-27-29-AE-0B" Called-Station-Id = "D4-CA-6D-A6-53-7B:eduroam test" NAS-Identifier = "MikroTik" NAS-IP-Address = 192.168.1.254 server inner-tunnel { # Executing section authorize from file /etc/raddb/sites-enabled/inner-tunnel +- entering group authorize {...} [auth_log] expand: /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] expand: %t -> Thu Mar 13 16:17:17 2014 ++[auth_log] returns ok [eap] EAP packet type response id 7 length 75 [eap] No EAP Start, assuming it's an on-going EAP conversation ++[eap] returns updated ++[files] returns noop ++[mschap] returns noop [pap] WARNING! No "known good" password found for the user. Authentication may fail because of this. ++[pap] returns noop Found Auth-Type = EAP # Executing group from file /etc/raddb/sites-enabled/inner-tunnel +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/mschapv2 [eap] processing type mschapv2 [mschapv2] # Executing group from file /etc/raddb/sites-enabled/inner-tunnel [mschapv2] +- entering group MS-CHAP {...} [mschap] Creating challenge hash with username: user5(a)igor.local [mschap] Told to do MS-CHAPv2 for user5(a)igor.local with NT-Password [mschap] expand: %{Stripped-User-Name} -> [mschap] ... expanding second conditional [mschap] expand: %{User-Name} -> user5(a)igor.local [mschap] expand: %{%{User-Name}:-None} -> user5(a)igor.local [mschap] expand: --username=%{%{Stripped-User-Name}:-%{%{User-Name}:-None}} -> --username=user5(a)igor.local [mschap] Creating challenge hash with username: user5(a)igor.local [mschap] expand: %{mschap:Challenge} -> 13479bee1886303a [mschap] expand: --challenge=%{%{mschap:Challenge}:-00} -> --challenge=13479bee1886303a [mschap] expand: %{mschap:NT-Response} -> a333f4999fd9698891d2fbc675892007431387796abfa13b [mschap] expand: --nt-response=%{%{mschap:NT-Response}:-00} -> --nt-response=a333f4999fd9698891d2fbc675892007431387796abfa13b WARNING: The "idmap uid" option is deprecated WARNING: The "idmap gid" option is deprecated Exec-Program output: Logon failure (0xc000006d) Exec-Program-Wait: plaintext: Logon failure (0xc000006d) Exec-Program: returned: 1 [mschap] External script failed. [mschap] FAILED: MS-CHAP2-Response is incorrect ++[mschap] returns reject [eap] Freeing handler ++[eap] returns reject Failed to authenticate the user. } # server inner-tunnel [peap] Got tunneled reply code 3 MS-CHAP-Error = "\007E=691 R=1" EAP-Message = 0x04070004 Message-Authenticator = 0x00000000000000000000000000000000 [peap] Got tunneled reply RADIUS code 3 MS-CHAP-Error = "\007E=691 R=1" EAP-Message = 0x04070004 Message-Authenticator = 0x00000000000000000000000000000000 [peap] Tunneled authentication was rejected. [peap] FAILURE ++[eap] returns handled Sending Access-Challenge of id 141 to 192.168.1.254 port 47603 EAP-Message = 0x0108002b19001703010020849be569aa66a8021ded29e15410bf74c59467c7db34f5995d1b6f91ebe9f22f Message-Authenticator = 0x00000000000000000000000000000000 State = 0x3ce95d9d3be144ec4019661d07f2a324 Finished request 7. Going to the next request Waking up in 4.7 seconds. rad_recv: Access-Request packet from host 192.168.1.254 port 49384, id=142, length=248 Service-Type = Framed-User Framed-MTU = 1400 User-Name = "user5(a)igor.local" State = 0x3ce95d9d3be144ec4019661d07f2a324 NAS-Port-Id = "wlan1" NAS-Port-Type = Wireless-802.11 Calling-Station-Id = "5C-3C-27-29-AE-0B" Called-Station-Id = "D4-CA-6D-A6-53-7B:eduroam test" EAP-Message = 0x02080050190017030100200ebc17e9bd20cc45f1c1772575868316464689e08bba6f4d934e5e1e4be3b040170301002078626c70503034ac41b926af5220e841c57e097b8fe870a8a49fdf71c2e749f8 Message-Authenticator = 0x658f4273b0c1858d67761528c08eac05 NAS-Identifier = "MikroTik" NAS-IP-Address = 192.168.1.254 # Executing section authorize from file /etc/raddb/sites-enabled/default +- entering group authorize {...} ++[preprocess] returns ok [auth_log] expand: /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d -> /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] /var/log/radius/radacct/%{Client-IP-Address}/auth-detail-%Y%m%d expands to /var/log/radius/radacct/192.168.1.254/auth-detail-20140313 [auth_log] expand: %t -> Thu Mar 13 16:17:17 2014 ++[auth_log] returns ok ++[mschap] returns noop [suffix] Looking up realm "igor.local" for User-Name = "user5(a)igor.local" [suffix] Found realm "igor.local" [suffix] Adding Stripped-User-Name = "user5" [suffix] Adding Realm = "igor.local" [suffix] Authentication realm is LOCAL. ++[suffix] returns ok [eap] EAP packet type response id 8 length 80 [eap] Continuing tunnel setup. ++[eap] returns ok Found Auth-Type = EAP # Executing group from file /etc/raddb/sites-enabled/default +- entering group authenticate {...} [eap] Request found, released from the list [eap] EAP/peap [eap] processing type peap [peap] processing EAP-TLS [peap] eaptls_verify returned 7 [peap] Done initial handshake [peap] eaptls_process returned 7 [peap] EAPTLS_OK [peap] Session established. Decoding tunneled attributes. [peap] Peap state send tlv failure [peap] Received EAP-TLV response. [peap] The users session was previously rejected: returning reject (again.) [peap] *** This means you need to read the PREVIOUS messages in the debug output [peap] *** to find out the reason why the user was rejected. [peap] *** Look for "reject" or "fail". Those earlier messages will tell you. [peap] *** what went wrong, and how to fix the problem. [eap] Handler failed in EAP/peap [eap] Failed in EAP select ++[eap] returns invalid Failed to authenticate the user. Using Post-Auth-Type Reject # Executing group from file /etc/raddb/sites-enabled/default +- entering group REJECT {...} [attr_filter.access_reject] expand: %{User-Name} -> user5(a)igor.local attr_filter: Matched entry DEFAULT at line 11 ++[attr_filter.access_reject] returns updated Delaying reject of request 8 for 1 seconds Going to the next request Waking up in 0.9 seconds. Sending delayed reject for request 8 Sending Access-Reject of id 142 to 192.168.1.254 port 49384 EAP-Message = 0x04080004 Message-Authenticator = 0x00000000000000000000000000000000 Waking up in 3.7 seconds. Cleaning up request 0 ID 134 with timestamp +3480 Cleaning up request 1 ID 135 with timestamp +3480 Cleaning up request 2 ID 136 with timestamp +3480 Cleaning up request 3 ID 137 with timestamp +3480 Cleaning up request 4 ID 138 with timestamp +3480 Cleaning up request 5 ID 139 with timestamp +3480 Cleaning up request 6 ID 140 with timestamp +3480 Cleaning up request 7 ID 141 with timestamp +3480 Waking up in 1.0 seconds. Cleaning up request 8 ID 142 with timestamp +3480 Ready to process requests.

2 1

mail archive problems?
by John Dennis 13 Mar '14

13 Mar '14

The http://freeradius.org/list/users.html page says the mailing list archive can be found here: http://lists.freeradius.org/pipermail/freeradius-users/ But the server replies: The requested URL /pipermail/freeradius-users/ was not found on this server. Whats up? -- John

3 3

Abu
by Nadir Aliyev 13 Mar '14

13 Mar '14

Hello, I use rlm_sql module and have problems with stale sessions. Sometimes users (more than 4k-5k) disconnects without accounting_stop_query and they can't connect again because session is not closed by normal way and session still active in database. My accounting time is 45 minutes, if new alive-update packets not coming for user from NAS side I close this session manually with a little script. After this process (after 45 minute) user can connects again. I used stale_sessions but it not helped. In my case I want to close for user existing staled session in database if new accounting_start_query (insert query) occurs. How can i realize this? accounting_start_query = "INSERT into ${acct_table1} \ (AcctSessionId, AcctUniqueId, UserName, Realm, NASIPAddress, NASPortId, NASPortType, AcctStartTime, AcctAuthentic, \ ConnectInfo_start, CalledStationId, CallingStationId, ServiceType, FramedProtocol, FramedIPAddress, AcctStartDelay, XAscendSessionSvrKey, mac_address, agent, vlan) \ values('%{Acct-Session-Id}', '%{Acct-Unique-Session-Id}', '%{SQL-User-Name}', '%{Realm}', '%{NAS-IP-Address}', \ '%{NAS-Port}', '%{NAS-Port-Type}', ('%S'::timestamp - '%{%{Acct-Delay-Time}:-0}'::interval), '%{Acct-Authentic}', '%{Connect-Info}', \ '%{Called-Station-Id}', '%{Calling-Station-Id}', '%{Service-Type}', '%{Framed-Protocol}', \ NULLIF('%{Framed-IP-Address}', '')::inet, 0, '%{X-Ascend-Session-Svr-Key}', \ (case when length('%{client-mac-address}')>0 then '%{client-mac-address}' else '%{Calling-Station-Id}' end), \ (case when '%{NAS-IP-Address}' = '10.10.10.10' then '%{Nas-Port-Id}' else '%{circuit-id-tag}' end), \ '%{Nas-Port-Id}')" accounting_start_query_alt = "UPDATE ${acct_table1} \ SET AcctStartTime = ('%S'::timestamp - '%{Acct-Delay-Time:-0}'::interval), AcctStartDelay = 0, \ ConnectInfo_start = '%{Connect-Info}' WHERE AcctSessionId = '%{Acct-Session-Id}' AND UserName = '%{SQL-User-Name}' \ AND NASIPAddress = '%{NAS-IP-Address}' AND AcctStopTime IS NULL" Thanks! Nadir

2 1