Hello, I described problem previous email. Please find attached Linksys only debug information. 1.Linksys ======== . . . rad_recv: Access-Request packet from host 10.0.1.254 port 1076, id=0, length=176 Message-Authenticator = 0x09f3d899800a95c2a2d21b4f2e627112 Service-Type = Framed-User User-Name = "test" Framed-MTU = 1488 Called-Station-Id = "00-C0-A8-ED-84-11:CSMS" Calling-Station-Id = "00-18-F8-44-15-C0" NAS-Identifier = "AP CSMS" NAS-Port-Type = Wireless-802.11 Connect-Info = "CONNECT 54Mbps 802.11g" EAP-Message = 0x020000090174657374 NAS-IP-Address = 10.0.1.254 NAS-Port = 1 NAS-Port-Id = "STA port # 1" Sending Access-Challenge of id 0 to 10.0.1.254 port 1076 EAP-Message = 0x010100061920 Message-Authenticator = 0x00000000000000000000000000000000 State = 0xa0a5a769a0a4be65b7c70cf134208ef5 Sat May 23 06:08:18 2009 : Info: Finished request 55. Sat May 23 06:08:18 2009 : Debug: Going to the next request Sat May 23 06:08:18 2009 : Debug: Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 10.0.1.254 port 1076, id=1, length=297 Message-Authenticator = 0xcf9d263efbdfb5ab9d56e8cc7ec47b7d Service-Type = Framed-User User-Name = "test" Framed-MTU = 1488 State = 0xa0a5a769a0a4be65b7c70cf134208ef5 Called-Station-Id = "00-C0-A8-ED-84-11:CSMS" Calling-Station-Id = "00-18-F8-44-15-C0" NAS-Identifier = "AP CSMS" NAS-Port-Type = Wireless-802.11 Connect-Info = "CONNECT 54Mbps 802.11g" EAP-Message = 0x0201007019800000006616030100610100005d03014a17224d977e6fe8bb7128bc74538c018dd274e628a268fe0a4b441267a52e7c00003600390038003500160013000a00330032002f0007006600050004006300620061001500120009006500640060001400110008000600030100 NAS-IP-Address = 10.0.1.254 NAS-Port = 1 NAS-Port-Id = "STA port # 1" Sending Access-Challenge of id 1 to 10.0.1.254 port 1076 EAP-Message = 0x0102040019c000000a67160301002a0200002603014a1722524c9da49788a4d7f63597495a7101f5d24238247830bd681e7d5d3e670000390016030108180b0008140008110003903082038c30820274a003020102020101300d06092a864886f70d0101040500308183310b3009060355040613024d4e310b3009060355040813025542311430120603550407130b556c61616e6261617461723111300f060355040a1308486f6d656e65742e311f301d06092a864886f70d010901161074736565656b407961686f6f2e636f6d311d301b0603550403131454736572656e646f726a20617574686f72697479301e170d303930353232303131353138 EAP-Message = 0x5a170d3130303532323031313531385a3076310b3009060355040613024d4e310b30090603550408130255423111300f060355040a1308486f6d656e65742e312630240603550403131d54736572656e646f726a20536572766572204365727469666963617465311f301d06092a864886f70d010901161074736565656b407961686f6f2e636f6d30820122300d06092a864886f70d01010105000382010f003082010a0282010100d307b83ea71ed1c6ee8867dd355590ec1e9d04b2301d284eddf4983dd53bcd1fcc7d219fbacb9deb9ae5f64f1a2d660a15c7ffc995c6a9462a840d452c13676e68ebb427f3f8d4ccbb1141fdb9a6ac9d8027d17b EAP-Message = 0xfe2e02e865530ad0e174d309e79164ce2ab8df0a7c676155022f298a0014bab98b68e23a06cf939d9c6b53fab8cb7b123df2b751a700ae1b12129cd05dcc6110c8b89f9b467142874b7249ae442d1680700f96ea69f511c388f198869375b1e86af0e4320dc81e6ec5dfa88817fef561b6c69561e6200412327b72d5915c9c292eaa2f4914c0ca4a102bd1ba198aa3224e20e8c635f9b4131042955409ab565377e64b005bf7ffd6db0af64f0203010001a317301530130603551d25040c300a06082b06010505070301300d06092a864886f70d0101040500038201010015a7d2ade3e88c984c89476f7823e6e178e82374fb9716b8aaae950f1d2040 EAP-Message = 0xca57298fb83500fed0f2c3f575f86cf242cc938cac77017fd0363d7e66618fc097b2221633733ab7e6ca92c5b02a455ef00820b642cfc955226e708f237981d52f4d25f5dbe60d094a22cdc32b2d09cfa4f4f959af0487552edf4835d20109016bbde20a5b79ab4cdc124ad0d6eb269cc6b2409f7f7c7221c6f03593032d7417d946af889568ca0ac8ee94786b8e8046a7e1e2fd93e1256dc4ed5cd62282b6f2cf02c0d5875867f2869c002bac9f7a6ef27e313e44492c04faa7d320ffe47d21e46b179945379d2834b9f23d8391fc44511ab29cb87950aca10042e2ba48fd3e9c00047b308204773082035fa0030201020209008b45c0e25d2e249a30 EAP-Message = 0x0d06092a864886f70d010105 Message-Authenticator = 0x00000000000000000000000000000000 State = 0xa0a5a769a1a7be65b7c70cf134208ef5 Sat May 23 06:08:18 2009 : Info: Finished request 56. Sat May 23 06:08:18 2009 : Debug: Going to the next request Sat May 23 06:08:18 2009 : Debug: Waking up in 4.9 seconds. rad_recv: Access-Request packet from host 10.0.1.254 port 1076, id=2, length=191 Message-Authenticator = 0x4dbbc0fe7dca23743b20d9a862ccf0a0 Service-Type = Framed-User User-Name = "test" Framed-MTU = 1488 State = 0xa0a5a769a1a7be65b7c70cf134208ef5 Called-Station-Id = "00-C0-A8-ED-84-11:CSMS" Calling-Station-Id = "00-18-F8-44-15-C0" NAS-Identifier = "AP CSMS" NAS-Port-Type = Wireless-802.11 Connect-Info = "CONNECT 54Mbps 802.11g" EAP-Message = 0x020200061900 NAS-IP-Address = 10.0.1.254 NAS-Port = 1 NAS-Port-Id = "STA port # 1" Sending Access-Challenge of id 2 to 10.0.1.254 port 1076 EAP-Message = 0x010303fc19400500308183310b3009060355040613024d4e310b3009060355040813025542311430120603550407130b556c61616e6261617461723111300f060355040a1308486f6d656e65742e311f301d06092a864886f70d010901161074736565656b407961686f6f2e636f6d311d301b0603550403131454736572656e646f726a20617574686f72697479301e170d3039303532323031313530345a170d3130303532323031313530345a308183310b3009060355040613024d4e310b3009060355040813025542311430120603550407130b556c61616e6261617461723111300f060355040a1308486f6d656e65742e311f301d06092a8648 EAP-Message = 0x86f70d010901161074736565656b407961686f6f2e636f6d311d301b0603550403131454736572656e646f726a20617574686f7269747930820122300d06092a864886f70d01010105000382010f003082010a0282010100f5a581f6620f0dc9f19e0eb69943d39130695d3ac1cb6ce54907a7c67f6a0dcc674e78bf6fb8167ea02010a34035f5ba9ab8e5c494e49cf1d907db59e4e178dcb8e858cc2bd488f90a7339ea1526cd187b4eee2e4fdf6d121d1b49921fb8c7b17e11f89ae43e1179874cfbaba3407dfeea6b8ff0698b8dcfbd38ca6236bf1bff2e5fbe46d408b7f14cd35caa7ed972abf3f5ee12d07cd464fcecde6a475188b0e692dfad59 EAP-Message = 0xf670d03d01a26a8d469a6578ac0a59843565e53b2bc145919c3fef40c0e693645c644f50111f489d8115bf19af99a870c42ef8f0bea4df4e2b334460f6925eb73aff6c4ad2e4a9219005f57df0b5452229e337f2ef420d7c8dce8b0203010001a381eb3081e8301d0603551d0e041604140692c35ad5374aa0aca7a7407f0d255ed0719d303081b80603551d230481b03081ad80140692c35ad5374aa0aca7a7407f0d255ed0719d30a18189a48186308183310b3009060355040613024d4e310b3009060355040813025542311430120603550407130b556c61616e6261617461723111300f060355040a1308486f6d656e65742e311f301d06092a86 EAP-Message = 0x4886f70d010901161074736565656b407961686f6f2e636f6d311d301b0603550403131454736572656e646f726a20617574686f726974798209008b45c0e25d2e249a300c0603551d13040530030101ff300d06092a864886f70d010105050003820101007675cc71a220f326022857ac3670dd7540d9159c200dff570f331034e690b1418f2444083524b298ee48192da8f172a324574953c06149e4cb94dd89a2142e621d6ffafb7a815fbb417112fcd4ab2a0ed4645407be28896a6ba6b22cd7389ba79420ea233305c88be323a9e122761b27f747119a35714822d0b4ae66c3d7ecceb0594fe9c830de22a08a8b05cda84cc1b5c792089a309014 EAP-Message = 0xe78e4d77cbec2fb9 Message-Authenticator = 0x00000000000000000000000000000000 State = 0xa0a5a769a2a6be65b7c70cf134208ef5 Sat May 23 06:08:19 2009 : Info: Finished request 57. Sat May 23 06:08:19 2009 : Debug: Going to the next request Sat May 23 06:08:19 2009 : Debug: Waking up in 4.4 seconds. rad_recv: Access-Request packet from host 10.0.1.254 port 1076, id=3, length=191 Message-Authenticator = 0xfda5026403bab578a19ced16bfb4ca06 Service-Type = Framed-User User-Name = "test" Framed-MTU = 1488 State = 0xa0a5a769a2a6be65b7c70cf134208ef5 Called-Station-Id = "00-C0-A8-ED-84-11:CSMS" Calling-Station-Id = "00-18-F8-44-15-C0" NAS-Identifier = "AP CSMS" NAS-Port-Type = Wireless-802.11 Connect-Info = "CONNECT 54Mbps 802.11g" EAP-Message = 0x020300061900 NAS-IP-Address = 10.0.1.254 NAS-Port = 1 NAS-Port-Id = "STA port # 1" Sat May 23 06:08:19 2009 : Info: +- entering group authorize {...} Sat May 23 06:08:19 2009 : Info: ++[preprocess] returns ok Sat May 23 06:08:19 2009 : Info: ++[chap] returns noop Sat May 23 06:08:19 2009 : Info: ++[mschap] returns noop Sat May 23 06:08:19 2009 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL Sat May 23 06:08:19 2009 : Info: [suffix] No such realm "NULL" Sat May 23 06:08:19 2009 : Info: ++[suffix] returns noop Sat May 23 06:08:19 2009 : Info: [eap] EAP packet type response id 3 length 6 Sat May 23 06:08:19 2009 : Info: [eap] Continuing tunnel setup. Sat May 23 06:08:19 2009 : Info: ++[eap] returns ok Sat May 23 06:08:19 2009 : Info: Found Auth-Type = EAP Sat May 23 06:08:19 2009 : Info: +- entering group authenticate {...} Sat May 23 06:08:19 2009 : Info: [eap] Request found, released from the list Sat May 23 06:08:19 2009 : Info: [eap] EAP/peap Sat May 23 06:08:19 2009 : Info: [eap] processing type peap Sat May 23 06:08:19 2009 : Info: [peap] processing EAP-TLS Sat May 23 06:08:19 2009 : Info: [peap] Received TLS ACK Sat May 23 06:08:19 2009 : Info: [peap] ACK handshake fragment handler Sat May 23 06:08:19 2009 : Info: [peap] eaptls_verify returned 1 Sat May 23 06:08:19 2009 : Info: [peap] eaptls_process returned 13 Sat May 23 06:08:19 2009 : Info: [peap] EAPTLS_HANDLED Sat May 23 06:08:19 2009 : Info: ++[eap] returns handled Sending Access-Challenge of id 3 to 10.0.1.254 port 1076 EAP-Message = 0x010402811900dffe3ea966d2f1d6b3f53ff8d7aca3209d32404ff2ea681dfe4e453405f0b9de3eee8c146c2e3ba3dcae73743550c267bc22ca8c9cb6da0d1b78606e707f17f535a4fda33aa48734e794b5e8c345dbe48a9715de963d83ae51473edcafff79bf160301020d0c0002090080c8659d434cd62c0eaec8d1eaef67094fc7dd52c72dc7e38e55ad4d74d9f14735cd89c320acef6ce8c251a50e943f4e92727f9f34bd997db7c16d1785e8b25d750946d21dae73209f0e41991b36edb184e1e3c475b9fbacab20ff57693c64007781193881c119dcee954c563c5e032929289a129de77e748167d2afd7982af81b000102008088a93c29af03b5 EAP-Message = 0x5fc69caa012ef601c99b04c0672b22de6742aadecbf90cffe8e72a8052520debf89eb6cd8670a01f24f744663c24a2542f6760179db8707c675018a7705fd89056403a5bd856f61a8e9d180866cb4320c3dccfff6efc269c09cca5a525c6216f2aa6708fe9c8bc2ce58583f492af5d9448665b22af5b298237010027fe410d157789a34a6cc996af6047db475d5bebfceb9072e29e95397474334b6b67d8621fedcefc52683efb363faf096749d20218cf92c08aa558e874782c9b9e199e274eb9b2eae0d9b6a407bf01a5acef8ae7161cb3736839567b3cc6b3729a5ce4b98447230fde4ed918f9e9b2028f0f414ba0887f748936e6f2181e73618ce5 EAP-Message = 0xb74090f454843a8d02d6a9a7ce694c91cd8d3f7f6bf965d5dca7ac9170a50c2d67e0ba8576b73d2fc9d6d1334998b14b35c0aa4773e2e1f3df4d4e14c0cfa9892dfed0f4fee4dbd560d8f17754af49b93f49b37179109914e794073a19a4f2d2381c269aae135e089f898d558f9246c1be2a5d0cf827201eb9872293417b16030100040e000000 Message-Authenticator = 0x00000000000000000000000000000000 State = 0xa0a5a769a3a1be65b7c70cf134208ef5 Sat May 23 06:08:19 2009 : Info: Finished request 58. Sat May 23 06:08:19 2009 : Debug: Going to the next request Sat May 23 06:08:19 2009 : Debug: Waking up in 4.3 seconds. rad_recv: Access-Request packet from host 10.0.1.254 port 1076, id=4, length=393 Message-Authenticator = 0xc3dbc64530ddfbf01b82be637c015e3e Service-Type = Framed-User User-Name = "test" Framed-MTU = 1488 State = 0xa0a5a769a3a1be65b7c70cf134208ef5 Called-Station-Id = "00-C0-A8-ED-84-11:CSMS" Calling-Station-Id = "00-18-F8-44-15-C0" NAS-Identifier = "AP CSMS" NAS-Port-Type = Wireless-802.11 Connect-Info = "CONNECT 54Mbps 802.11g" EAP-Message = 0x020400d01980000000c616030100861000008200803c767a3bed3fa96f9349bab37ac44b5db38abeda92b001fe54413b595317420c1f681027b921a78213f080ef59a208541ee5f780b2606acae7bb215bb7c483063a15271ddf202abbe73d3bda20d5dfb82277821f8f625c9c20e1cea7d21b23a32536dd5abffcb1aabf61cfc383d03d881847338bd7075f5f9ab02980ab2fc6051403010001011603010030b0b61396697f5ee1d3de6866e00912a6fbd0d339d13218b5661affca34fed8c08b17970c93036f1677ab3e5e55e88af9 NAS-IP-Address = 10.0.1.254 NAS-Port = 1 NAS-Port-Id = "STA port # 1" Sat May 23 06:08:19 2009 : Info: +- entering group authorize {...} Sat May 23 06:08:19 2009 : Info: ++[preprocess] returns ok Sat May 23 06:08:19 2009 : Info: ++[chap] returns noop Sat May 23 06:08:19 2009 : Info: ++[mschap] returns noop Sat May 23 06:08:19 2009 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL Sat May 23 06:08:19 2009 : Info: [suffix] No such realm "NULL" Sat May 23 06:08:19 2009 : Info: ++[suffix] returns noop Sat May 23 06:08:19 2009 : Info: [eap] EAP packet type response id 4 length 208 Sat May 23 06:08:19 2009 : Info: [eap] Continuing tunnel setup. Sat May 23 06:08:19 2009 : Info: ++[eap] returns ok Sat May 23 06:08:19 2009 : Info: Found Auth-Type = EAP Sat May 23 06:08:19 2009 : Info: +- entering group authenticate {...} Sat May 23 06:08:19 2009 : Info: [eap] Request found, released from the list Sat May 23 06:08:19 2009 : Info: [eap] EAP/peap Sat May 23 06:08:19 2009 : Info: [eap] processing type peap Sat May 23 06:08:19 2009 : Info: [peap] processing EAP-TLS Sat May 23 06:08:19 2009 : Debug: TLS Length 198 Sat May 23 06:08:19 2009 : Info: [peap] Length Included Sat May 23 06:08:19 2009 : Info: [peap] eaptls_verify returned 11 Sat May 23 06:08:19 2009 : Info: [peap] <<< TLS 1.0 Handshake [length 0086], ClientKeyExchange Sat May 23 06:08:19 2009 : Info: [peap] TLS_accept: SSLv3 read client key exchange A Sat May 23 06:08:19 2009 : Info: [peap] <<< TLS 1.0 ChangeCipherSpec [length 0001] Sat May 23 06:08:19 2009 : Info: [peap] <<< TLS 1.0 Handshake [length 0010], Finished Sat May 23 06:08:19 2009 : Info: [peap] TLS_accept: SSLv3 read finished A Sat May 23 06:08:19 2009 : Info: [peap] >>> TLS 1.0 ChangeCipherSpec [length 0001] Sat May 23 06:08:19 2009 : Info: [peap] TLS_accept: SSLv3 write change cipher spec A Sat May 23 06:08:19 2009 : Info: [peap] >>> TLS 1.0 Handshake [length 0010], Finished Sat May 23 06:08:19 2009 : Info: [peap] TLS_accept: SSLv3 write finished A Sat May 23 06:08:19 2009 : Info: [peap] TLS_accept: SSLv3 flush data Sat May 23 06:08:19 2009 : Info: [peap] (other): SSL negotiation finished successfully Sat May 23 06:08:19 2009 : Debug: SSL Connection Established Sat May 23 06:08:19 2009 : Info: [peap] eaptls_process returned 13 Sat May 23 06:08:19 2009 : Info: [peap] EAPTLS_HANDLED Sat May 23 06:08:19 2009 : Info: ++[eap] returns handled Sending Access-Challenge of id 4 to 10.0.1.254 port 1076 EAP-Message = 0x0105004119001403010001011603010030d14fffc16a5e0f7ba751a28eea1437de326ad1de3376133498aad572a5a52f4e9ef40b2ecae959253f8d6c3a811bf528 Message-Authenticator = 0x00000000000000000000000000000000 State = 0xa0a5a769a4a0be65b7c70cf134208ef5 Sat May 23 06:08:19 2009 : Info: Finished request 59. Sat May 23 06:08:19 2009 : Debug: Going to the next request Sat May 23 06:08:19 2009 : Debug: Waking up in 4.2 seconds. rad_recv: Access-Request packet from host 10.0.1.254 port 1076, id=5, length=191 Message-Authenticator = 0x81bcaeac15608ae8414a0b28c31dcba6 Service-Type = Framed-User User-Name = "test" Framed-MTU = 1488 State = 0xa0a5a769a4a0be65b7c70cf134208ef5 Called-Station-Id = "00-C0-A8-ED-84-11:CSMS" Calling-Station-Id = "00-18-F8-44-15-C0" NAS-Identifier = "AP CSMS" NAS-Port-Type = Wireless-802.11 Connect-Info = "CONNECT 54Mbps 802.11g" EAP-Message = 0x020500061900 NAS-IP-Address = 10.0.1.254 NAS-Port = 1 NAS-Port-Id = "STA port # 1" Sat May 23 06:08:19 2009 : Info: +- entering group authorize {...} Sat May 23 06:08:19 2009 : Info: ++[preprocess] returns ok Sat May 23 06:08:19 2009 : Info: ++[chap] returns noop Sat May 23 06:08:19 2009 : Info: ++[mschap] returns noop Sat May 23 06:08:19 2009 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL Sat May 23 06:08:19 2009 : Info: [suffix] No such realm "NULL" Sat May 23 06:08:19 2009 : Info: ++[suffix] returns noop Sat May 23 06:08:19 2009 : Info: [eap] EAP packet type response id 5 length 6 Sat May 23 06:08:19 2009 : Info: [eap] Continuing tunnel setup. Sat May 23 06:08:19 2009 : Info: ++[eap] returns ok Sat May 23 06:08:19 2009 : Info: Found Auth-Type = EAP Sat May 23 06:08:19 2009 : Info: +- entering group authenticate {...} Sat May 23 06:08:19 2009 : Info: [eap] Request found, released from the list Sat May 23 06:08:19 2009 : Info: [eap] EAP/peap Sat May 23 06:08:19 2009 : Info: [eap] processing type peap Sat May 23 06:08:19 2009 : Info: [peap] processing EAP-TLS Sat May 23 06:08:19 2009 : Info: [peap] Received TLS ACK Sat May 23 06:08:19 2009 : Info: [peap] ACK handshake is finished Sat May 23 06:08:19 2009 : Info: [peap] eaptls_verify returned 3 Sat May 23 06:08:19 2009 : Info: [peap] eaptls_process returned 3 Sat May 23 06:08:19 2009 : Info: [peap] EAPTLS_SUCCESS Sat May 23 06:08:19 2009 : Info: ++[eap] returns handled Sending Access-Challenge of id 5 to 10.0.1.254 port 1076 EAP-Message = 0x0106002b190017030100205765bd754394302906d7c5624244577db1c773bf6abb0da3dffcf7a11364c46c Message-Authenticator = 0x00000000000000000000000000000000 State = 0xa0a5a769a5a3be65b7c70cf134208ef5 Sat May 23 06:08:19 2009 : Info: Finished request 60. Sat May 23 06:08:19 2009 : Debug: Going to the next request Sat May 23 06:08:19 2009 : Debug: Waking up in 4.2 seconds. rad_recv: Access-Request packet from host 10.0.1.254 port 1076, id=6, length=265 Message-Authenticator = 0x87072a36bbcda8c50e9e7f0b602aa9d6 Service-Type = Framed-User User-Name = "test" Framed-MTU = 1488 State = 0xa0a5a769a5a3be65b7c70cf134208ef5 Called-Station-Id = "00-C0-A8-ED-84-11:CSMS" Calling-Station-Id = "00-18-F8-44-15-C0" NAS-Identifier = "AP CSMS" NAS-Port-Type = Wireless-802.11 Connect-Info = "CONNECT 54Mbps 802.11g" EAP-Message = 0x0206005019001703010020529fd268b46c8afb5e648ce6a6aaa6927f8d8d55c1f63a091da6dfe631d717491703010020716f10f05b5a400b73c2a7fc8df3e15e905a78d6e3ab16f2681b1827456051a9 NAS-IP-Address = 10.0.1.254 NAS-Port = 1 NAS-Port-Id = "STA port # 1" Sat May 23 06:08:19 2009 : Info: +- entering group authorize {...} Sat May 23 06:08:19 2009 : Info: ++[preprocess] returns ok Sat May 23 06:08:19 2009 : Info: ++[chap] returns noop Sat May 23 06:08:19 2009 : Info: ++[mschap] returns noop Sat May 23 06:08:19 2009 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL Sat May 23 06:08:19 2009 : Info: [suffix] No such realm "NULL" Sat May 23 06:08:19 2009 : Info: ++[suffix] returns noop Sat May 23 06:08:19 2009 : Info: [eap] EAP packet type response id 6 length 80 Sat May 23 06:08:19 2009 : Info: [eap] Continuing tunnel setup. Sat May 23 06:08:19 2009 : Info: ++[eap] returns ok Sat May 23 06:08:19 2009 : Info: Found Auth-Type = EAP Sat May 23 06:08:19 2009 : Info: +- entering group authenticate {...} Sat May 23 06:08:19 2009 : Info: [eap] Request found, released from the list Sat May 23 06:08:19 2009 : Info: [eap] EAP/peap Sat May 23 06:08:19 2009 : Info: [eap] processing type peap Sat May 23 06:08:19 2009 : Info: [peap] processing EAP-TLS Sat May 23 06:08:19 2009 : Info: [peap] eaptls_verify returned 7 Sat May 23 06:08:19 2009 : Info: [peap] Done initial handshake Sat May 23 06:08:19 2009 : Info: [peap] eaptls_process returned 7 Sat May 23 06:08:19 2009 : Info: [peap] EAPTLS_OK Sat May 23 06:08:19 2009 : Info: [peap] Session established. Decoding tunneled attributes. Sat May 23 06:08:19 2009 : Info: [peap] Identity - test Sat May 23 06:08:19 2009 : Info: [peap] Got tunneled request EAP-Message = 0x020600090174657374 server { Sat May 23 06:08:19 2009 : Debug: PEAP: Got tunneled identity of test Sat May 23 06:08:19 2009 : Debug: PEAP: Setting default EAP type for tunneled EAP session. Sat May 23 06:08:19 2009 : Debug: PEAP: Setting User-Name to test Sending tunneled request EAP-Message = 0x020600090174657374 FreeRADIUS-Proxied-To = 127.0.0.1 User-Name = "test" server { Sat May 23 06:08:19 2009 : Info: +- entering group authorize {...} Sat May 23 06:08:19 2009 : Info: ++[preprocess] returns ok Sat May 23 06:08:19 2009 : Info: ++[chap] returns noop Sat May 23 06:08:19 2009 : Info: ++[mschap] returns noop Sat May 23 06:08:19 2009 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL Sat May 23 06:08:19 2009 : Info: [suffix] No such realm "NULL" Sat May 23 06:08:19 2009 : Info: ++[suffix] returns noop Sat May 23 06:08:19 2009 : Info: [eap] EAP packet type response id 6 length 9 Sat May 23 06:08:19 2009 : Info: [eap] No EAP Start, assuming it's an on-going EAP conversation Sat May 23 06:08:19 2009 : Info: ++[eap] returns updated Sat May 23 06:08:19 2009 : Info: ++[unix] returns notfound Sat May 23 06:08:19 2009 : Info: [files] users: Matched entry test at line 206 Sat May 23 06:08:19 2009 : Info: ++[files] returns ok Sat May 23 06:08:19 2009 : Info: ++[expiration] returns noop Sat May 23 06:08:19 2009 : Info: ++[logintime] returns noop Sat May 23 06:08:19 2009 : Info: [pap] Found existing Auth-Type, not changing it. Sat May 23 06:08:19 2009 : Info: ++[pap] returns noop Sat May 23 06:08:19 2009 : Info: Found Auth-Type = EAP Sat May 23 06:08:19 2009 : Info: +- entering group authenticate {...} Sat May 23 06:08:19 2009 : Info: [eap] EAP Identity Sat May 23 06:08:19 2009 : Info: [eap] processing type mschapv2 Sat May 23 06:08:19 2009 : Debug: rlm_eap_mschapv2: Issuing Challenge Sat May 23 06:08:19 2009 : Info: ++[eap] returns handled } # server Sat May 23 06:08:19 2009 : Info: [peap] Got tunneled reply code 11 EAP-Message = 0x0107001e1a01070019105f46841a680c134eddbe469043e1685174657374 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x0e30cf7b0e37d5e801a16cd8c930d971 Sat May 23 06:08:19 2009 : Info: [peap] Got tunneled reply RADIUS code 11 EAP-Message = 0x0107001e1a01070019105f46841a680c134eddbe469043e1685174657374 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x0e30cf7b0e37d5e801a16cd8c930d971 Sat May 23 06:08:19 2009 : Info: [peap] Got tunneled Access-Challenge Sat May 23 06:08:19 2009 : Info: ++[eap] returns handled Sending Access-Challenge of id 6 to 10.0.1.254 port 1076 EAP-Message = 0x0107003b19001703010030e809ae694f14c8efbd16e0d03b4c55cbf81f3243b615a861b4523023daaed42cd0dd3d640345670b99ed665cba080847 Message-Authenticator = 0x00000000000000000000000000000000 State = 0xa0a5a769a6a2be65b7c70cf134208ef5 Sat May 23 06:08:19 2009 : Info: Finished request 61. Sat May 23 06:08:19 2009 : Debug: Going to the next request Sat May 23 06:08:19 2009 : Debug: Waking up in 4.1 seconds. rad_recv: Access-Request packet from host 10.0.1.254 port 1076, id=7, length=313 Message-Authenticator = 0xa3dfa44b322c9de16003e32d951586e3 Service-Type = Framed-User User-Name = "test" Framed-MTU = 1488 State = 0xa0a5a769a6a2be65b7c70cf134208ef5 Called-Station-Id = "00-C0-A8-ED-84-11:CSMS" Calling-Station-Id = "00-18-F8-44-15-C0" NAS-Identifier = "AP CSMS" NAS-Port-Type = Wireless-802.11 Connect-Info = "CONNECT 54Mbps 802.11g" EAP-Message = 0x0207008019001703010020df2362ed683aee9de5839bc43dfc36437f17cd3bba1672507087fe1f40c3a56f1703010050e584e37541e957f87c03300682884fd5cce115d8d84e7f654fdb97c35b2b09bf394e7b7ae815d8c6915a0aebdbb7cc081ef0c81c72f3779903ae486c58edb6288709ebdc48101909aa03f9ea6dc2d2b3 NAS-IP-Address = 10.0.1.254 NAS-Port = 1 NAS-Port-Id = "STA port # 1" Sat May 23 06:08:19 2009 : Info: +- entering group authorize {...} Sat May 23 06:08:19 2009 : Info: ++[preprocess] returns ok Sat May 23 06:08:19 2009 : Info: ++[chap] returns noop Sat May 23 06:08:19 2009 : Info: ++[mschap] returns noop Sat May 23 06:08:19 2009 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL Sat May 23 06:08:19 2009 : Info: [suffix] No such realm "NULL" Sat May 23 06:08:19 2009 : Info: ++[suffix] returns noop Sat May 23 06:08:19 2009 : Info: [eap] EAP packet type response id 7 length 128 Sat May 23 06:08:19 2009 : Info: [eap] Continuing tunnel setup. Sat May 23 06:08:19 2009 : Info: ++[eap] returns ok Sat May 23 06:08:19 2009 : Info: Found Auth-Type = EAP Sat May 23 06:08:19 2009 : Info: +- entering group authenticate {...} Sat May 23 06:08:19 2009 : Info: [eap] Request found, released from the list Sat May 23 06:08:19 2009 : Info: [eap] EAP/peap Sat May 23 06:08:19 2009 : Info: [eap] processing type peap Sat May 23 06:08:19 2009 : Info: [peap] processing EAP-TLS Sat May 23 06:08:19 2009 : Info: [peap] eaptls_verify returned 7 Sat May 23 06:08:19 2009 : Info: [peap] Done initial handshake Sat May 23 06:08:19 2009 : Info: [peap] eaptls_process returned 7 Sat May 23 06:08:19 2009 : Info: [peap] EAPTLS_OK Sat May 23 06:08:19 2009 : Info: [peap] Session established. Decoding tunneled attributes. Sat May 23 06:08:19 2009 : Info: [peap] EAP type mschapv2 Sat May 23 06:08:19 2009 : Info: [peap] Got tunneled request EAP-Message = 0x0207003f1a0207003a3191cc1a9946c6cfba2be8580af0c5cf500000000000000000afb10f6969c8a441a65674cb1b11bf2dd60e1b11800a2ac20074657374 server { Sat May 23 06:08:19 2009 : Debug: PEAP: Setting User-Name to test Sending tunneled request EAP-Message = 0x0207003f1a0207003a3191cc1a9946c6cfba2be8580af0c5cf500000000000000000afb10f6969c8a441a65674cb1b11bf2dd60e1b11800a2ac20074657374 FreeRADIUS-Proxied-To = 127.0.0.1 User-Name = "test" State = 0x0e30cf7b0e37d5e801a16cd8c930d971 server { Sat May 23 06:08:19 2009 : Info: +- entering group authorize {...} Sat May 23 06:08:19 2009 : Info: ++[preprocess] returns ok Sat May 23 06:08:19 2009 : Info: ++[chap] returns noop Sat May 23 06:08:19 2009 : Info: ++[mschap] returns noop Sat May 23 06:08:19 2009 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL Sat May 23 06:08:19 2009 : Info: [suffix] No such realm "NULL" Sat May 23 06:08:19 2009 : Info: ++[suffix] returns noop Sat May 23 06:08:19 2009 : Info: [eap] EAP packet type response id 7 length 63 Sat May 23 06:08:19 2009 : Info: [eap] No EAP Start, assuming it's an on-going EAP conversation Sat May 23 06:08:19 2009 : Info: ++[eap] returns updated Sat May 23 06:08:19 2009 : Info: ++[unix] returns notfound Sat May 23 06:08:19 2009 : Info: [files] users: Matched entry test at line 206 Sat May 23 06:08:19 2009 : Info: ++[files] returns ok Sat May 23 06:08:19 2009 : Info: ++[expiration] returns noop Sat May 23 06:08:19 2009 : Info: ++[logintime] returns noop Sat May 23 06:08:19 2009 : Info: [pap] Found existing Auth-Type, not changing it. Sat May 23 06:08:19 2009 : Info: ++[pap] returns noop Sat May 23 06:08:19 2009 : Info: Found Auth-Type = EAP Sat May 23 06:08:19 2009 : Info: +- entering group authenticate {...} Sat May 23 06:08:19 2009 : Info: [eap] Request found, released from the list Sat May 23 06:08:19 2009 : Info: [eap] EAP/mschapv2 Sat May 23 06:08:19 2009 : Info: [eap] processing type mschapv2 Sat May 23 06:08:19 2009 : Info: [mschapv2] +- entering group MS-CHAP {...} Sat May 23 06:08:19 2009 : Info: [mschap] Told to do MS-CHAPv2 for test with NT-Password Sat May 23 06:08:19 2009 : Info: [mschap] adding MS-CHAPv2 MPPE keys Sat May 23 06:08:19 2009 : Info: ++[mschap] returns ok Sat May 23 06:08:19 2009 : Debug: MSCHAP Success Sat May 23 06:08:19 2009 : Info: ++[eap] returns handled } # server Sat May 23 06:08:19 2009 : Info: [peap] Got tunneled reply code 11 EAP-Message = 0x010800331a0307002e533d38344635463445433735394531344635423138394545443141303541323245304139413446443439 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x0e30cf7b0f38d5e801a16cd8c930d971 Sat May 23 06:08:19 2009 : Info: [peap] Got tunneled reply RADIUS code 11 EAP-Message = 0x010800331a0307002e533d38344635463445433735394531344635423138394545443141303541323245304139413446443439 Message-Authenticator = 0x00000000000000000000000000000000 State = 0x0e30cf7b0f38d5e801a16cd8c930d971 Sat May 23 06:08:19 2009 : Info: [peap] Got tunneled Access-Challenge Sat May 23 06:08:19 2009 : Info: ++[eap] returns handled Sending Access-Challenge of id 7 to 10.0.1.254 port 1076 EAP-Message = 0x0108005b190017030100503f57f777451ccf96321b7674ea98d1ed79cde7eadb49087cf14047b05967ddd8d16c97f78c0062637b910c880d0ef9602ae33a26201f8e0c41d8c4f6ac646c37df62fb3d1d4bc766bab1a80515ca52ea Message-Authenticator = 0x00000000000000000000000000000000 State = 0xa0a5a769a7adbe65b7c70cf134208ef5 Sat May 23 06:08:19 2009 : Info: Finished request 62. Sat May 23 06:08:19 2009 : Debug: Going to the next request Sat May 23 06:08:19 2009 : Debug: Waking up in 4.1 seconds. rad_recv: Access-Request packet from host 10.0.1.254 port 1076, id=8, length=265 Message-Authenticator = 0x9e4f07f9de3e3e39fe346941a2ef4f37 Service-Type = Framed-User User-Name = "test" Framed-MTU = 1488 State = 0xa0a5a769a7adbe65b7c70cf134208ef5 Called-Station-Id = "00-C0-A8-ED-84-11:CSMS" Calling-Station-Id = "00-18-F8-44-15-C0" NAS-Identifier = "AP CSMS" NAS-Port-Type = Wireless-802.11 Connect-Info = "CONNECT 54Mbps 802.11g" EAP-Message = 0x0208005019001703010020c8092f2cb2ce2a55c1998c54dc28298f4d569ce89c5922c9271c2c2228e406a91703010020daa06674ef244c479b08189b459f73773c7977fa57afe0e7ff7dbffca6af39a3 NAS-IP-Address = 10.0.1.254 NAS-Port = 1 NAS-Port-Id = "STA port # 1" Sat May 23 06:08:19 2009 : Info: +- entering group authorize {...} Sat May 23 06:08:19 2009 : Info: ++[preprocess] returns ok Sat May 23 06:08:19 2009 : Info: ++[chap] returns noop Sat May 23 06:08:19 2009 : Info: ++[mschap] returns noop Sat May 23 06:08:19 2009 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL Sat May 23 06:08:19 2009 : Info: [suffix] No such realm "NULL" Sat May 23 06:08:19 2009 : Info: ++[suffix] returns noop Sat May 23 06:08:19 2009 : Info: [eap] EAP packet type response id 8 length 80 Sat May 23 06:08:19 2009 : Info: [eap] Continuing tunnel setup. Sat May 23 06:08:19 2009 : Info: ++[eap] returns ok Sat May 23 06:08:19 2009 : Info: Found Auth-Type = EAP Sat May 23 06:08:19 2009 : Info: +- entering group authenticate {...} Sat May 23 06:08:19 2009 : Info: [eap] Request found, released from the list Sat May 23 06:08:19 2009 : Info: [eap] EAP/peap Sat May 23 06:08:19 2009 : Info: [eap] processing type peap Sat May 23 06:08:19 2009 : Info: [peap] processing EAP-TLS Sat May 23 06:08:19 2009 : Info: [peap] eaptls_verify returned 7 Sat May 23 06:08:19 2009 : Info: [peap] Done initial handshake Sat May 23 06:08:19 2009 : Info: [peap] eaptls_process returned 7 Sat May 23 06:08:19 2009 : Info: [peap] EAPTLS_OK Sat May 23 06:08:19 2009 : Info: [peap] Session established. Decoding tunneled attributes. Sat May 23 06:08:19 2009 : Info: [peap] EAP type mschapv2 Sat May 23 06:08:19 2009 : Info: [peap] Got tunneled request EAP-Message = 0x020800061a03 server { Sat May 23 06:08:19 2009 : Debug: PEAP: Setting User-Name to test Sending tunneled request EAP-Message = 0x020800061a03 FreeRADIUS-Proxied-To = 127.0.0.1 User-Name = "test" State = 0x0e30cf7b0f38d5e801a16cd8c930d971 server { Sat May 23 06:08:19 2009 : Info: +- entering group authorize {...} Sat May 23 06:08:19 2009 : Info: ++[preprocess] returns ok Sat May 23 06:08:19 2009 : Info: ++[chap] returns noop Sat May 23 06:08:19 2009 : Info: ++[mschap] returns noop Sat May 23 06:08:19 2009 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL Sat May 23 06:08:19 2009 : Info: [suffix] No such realm "NULL" Sat May 23 06:08:19 2009 : Info: ++[suffix] returns noop Sat May 23 06:08:19 2009 : Info: [eap] EAP packet type response id 8 length 6 Sat May 23 06:08:19 2009 : Info: [eap] No EAP Start, assuming it's an on-going EAP conversation Sat May 23 06:08:19 2009 : Info: ++[eap] returns updated Sat May 23 06:08:19 2009 : Info: ++[unix] returns notfound Sat May 23 06:08:19 2009 : Info: [files] users: Matched entry test at line 206 Sat May 23 06:08:19 2009 : Info: ++[files] returns ok Sat May 23 06:08:19 2009 : Info: ++[expiration] returns noop Sat May 23 06:08:19 2009 : Info: ++[logintime] returns noop Sat May 23 06:08:19 2009 : Info: [pap] Found existing Auth-Type, not changing it. Sat May 23 06:08:19 2009 : Info: ++[pap] returns noop Sat May 23 06:08:19 2009 : Info: Found Auth-Type = EAP Sat May 23 06:08:19 2009 : Info: +- entering group authenticate {...} Sat May 23 06:08:19 2009 : Info: [eap] Request found, released from the list Sat May 23 06:08:19 2009 : Info: [eap] EAP/mschapv2 Sat May 23 06:08:19 2009 : Info: [eap] processing type mschapv2 Sat May 23 06:08:19 2009 : Info: [eap] Freeing handler Sat May 23 06:08:19 2009 : Info: ++[eap] returns ok Sat May 23 06:08:19 2009 : Auth: Login OK: [test/<via Auth-Type = EAP>] (from client home-ap port 0 via TLS tunnel) Sat May 23 06:08:19 2009 : Info: +- entering group post-auth {...} Sat May 23 06:08:19 2009 : Info: ++[exec] returns noop } # server Sat May 23 06:08:19 2009 : Info: [peap] Got tunneled reply code 2 EAP-Message = 0x03080004 Message-Authenticator = 0x00000000000000000000000000000000 User-Name = "test" Sat May 23 06:08:19 2009 : Info: [peap] Got tunneled reply RADIUS code 2 EAP-Message = 0x03080004 Message-Authenticator = 0x00000000000000000000000000000000 User-Name = "test" Sat May 23 06:08:19 2009 : Info: [peap] Tunneled authentication was successful. Sat May 23 06:08:19 2009 : Info: [peap] SUCCESS Sat May 23 06:08:19 2009 : Info: ++[eap] returns handled Sending Access-Challenge of id 8 to 10.0.1.254 port 1076 EAP-Message = 0x0109002b1900170301002009170dedd3f16e1a5db03f10fd34f3821b56dea549adec30b66acf406f2d4d5f Message-Authenticator = 0x00000000000000000000000000000000 State = 0xa0a5a769a8acbe65b7c70cf134208ef5 Sat May 23 06:08:19 2009 : Info: Finished request 63. Sat May 23 06:08:19 2009 : Debug: Going to the next request Sat May 23 06:08:19 2009 : Debug: Waking up in 4.1 seconds. rad_recv: Access-Request packet from host 10.0.1.254 port 1076, id=9, length=265 Message-Authenticator = 0x1bdfaa9f02815052e1c90d8bda0ad5f8 Service-Type = Framed-User User-Name = "test" Framed-MTU = 1488 State = 0xa0a5a769a8acbe65b7c70cf134208ef5 Called-Station-Id = "00-C0-A8-ED-84-11:CSMS" Calling-Station-Id = "00-18-F8-44-15-C0" NAS-Identifier = "AP CSMS" NAS-Port-Type = Wireless-802.11 Connect-Info = "CONNECT 54Mbps 802.11g" EAP-Message = 0x02090050190017030100201180bbdd1c0f51172eb6b157872924324c0fcbb2ba6232c881ae1eeac3b452ec1703010020ff76e4fb0e8a899a41b84b2078f1b6343f4b785038d1f41200a4b9ee8d69934a NAS-IP-Address = 10.0.1.254 NAS-Port = 1 NAS-Port-Id = "STA port # 1" Sat May 23 06:08:19 2009 : Info: +- entering group authorize {...} Sat May 23 06:08:19 2009 : Info: ++[preprocess] returns ok Sat May 23 06:08:19 2009 : Info: ++[chap] returns noop Sat May 23 06:08:19 2009 : Info: ++[mschap] returns noop Sat May 23 06:08:19 2009 : Info: [suffix] No '@' in User-Name = "test", looking up realm NULL Sat May 23 06:08:19 2009 : Info: [suffix] No such realm "NULL" Sat May 23 06:08:19 2009 : Info: ++[suffix] returns noop Sat May 23 06:08:19 2009 : Info: [eap] EAP packet type response id 9 length 80 Sat May 23 06:08:19 2009 : Info: [eap] Continuing tunnel setup. Sat May 23 06:08:19 2009 : Info: ++[eap] returns ok Sat May 23 06:08:19 2009 : Info: Found Auth-Type = EAP Sat May 23 06:08:19 2009 : Info: +- entering group authenticate {...} Sat May 23 06:08:19 2009 : Info: [eap] Request found, released from the list Sat May 23 06:08:19 2009 : Info: [eap] EAP/peap Sat May 23 06:08:19 2009 : Info: [eap] processing type peap Sat May 23 06:08:19 2009 : Info: [peap] processing EAP-TLS Sat May 23 06:08:19 2009 : Info: [peap] eaptls_verify returned 7 Sat May 23 06:08:19 2009 : Info: [peap] Done initial handshake Sat May 23 06:08:19 2009 : Info: [peap] eaptls_process returned 7 Sat May 23 06:08:19 2009 : Info: [peap] EAPTLS_OK Sat May 23 06:08:19 2009 : Info: [peap] Session established. Decoding tunneled attributes. Sat May 23 06:08:19 2009 : Info: [peap] Received EAP-TLV response. Sat May 23 06:08:19 2009 : Info: [peap] Success Sat May 23 06:08:19 2009 : Info: [eap] Freeing handler Sat May 23 06:08:19 2009 : Info: ++[eap] returns ok Sat May 23 06:08:19 2009 : Auth: Login OK: [test/<via Auth-Type = EAP>] (from client home-ap port 1 cli 00-18-F8-44-15-C0) Sat May 23 06:08:19 2009 : Info: +- entering group post-auth {...} Sat May 23 06:08:19 2009 : Info: ++[exec] returns noop Sending Access-Accept of id 9 to 10.0.1.254 port 1076 MS-MPPE-Recv-Key = 0xaee5abfa28444c372e4d93cb2143a55070b1571d659e6092782a7be2c21f1987 MS-MPPE-Send-Key = 0x025d7567a09cc9c1c513ca98c1d87b10b375e63f8c9d41db3f486f116864e131 EAP-Message = 0x03090004 Message-Authenticator = 0x00000000000000000000000000000000 User-Name = "test" Sat May 23 06:08:19 2009 : Info: Finished request 64. Sat May 23 06:08:19 2009 : Debug: Going to the next request Sat May 23 06:08:19 2009 : Debug: Waking up in 4.0 seconds. Sat May 23 06:08:23 2009 : Info: Cleaning up request 55 ID 0 with timestamp +434 Sat May 23 06:08:23 2009 : Info: Cleaning up request 56 ID 1 with timestamp +434 Sat May 23 06:08:23 2009 : Debug: Waking up in 0.5 seconds. Sat May 23 06:08:24 2009 : Info: Cleaning up request 57 ID 2 with timestamp +435 Sat May 23 06:08:24 2009 : Info: Cleaning up request 58 ID 3 with timestamp +435 Sat May 23 06:08:24 2009 : Debug: Waking up in 0.1 seconds. Sat May 23 06:08:24 2009 : Info: Cleaning up request 59 ID 4 with timestamp +435 Sat May 23 06:08:24 2009 : Info: Cleaning up request 60 ID 5 with timestamp +435 Sat May 23 06:08:24 2009 : Info: Cleaning up request 61 ID 6 with timestamp +435 Sat May 23 06:08:24 2009 : Info: Cleaning up request 62 ID 7 with timestamp +435 Sat May 23 06:08:24 2009 : Info: Cleaning up request 63 ID 8 with timestamp +435 Sat May 23 06:08:24 2009 : Info: Cleaning up request 64 ID 9 with timestamp +435 Sat May 23 06:08:24 2009 : Debug: Ready to process requests. Thanks, Balgaa