Hi, i have run 2 PPPoE service on my mikrotik:
1----> Server_PPPOE1
2----> Server_PPPOE2
because i want separate my client on 2 different networks
in radcheck table i have this ex user:
|| id || UserName || Attribute || op || Value ||
|| 253 || ttt || User-Password || == || ttt ||
|| 257 || ttt || Called-Station-Id || == || Server_PPPOE2 ||
And this is the debug message of radius:
rad_recv: Access-Request packet from host 10.10.10.252:3859, id=178, length=194
Service-Type = Framed-User
Framed-Protocol = PPP
NAS-Port = 2550
NAS-Port-Type = Ethernet
User-Name = “ttt”
Calling-Station-Id = “00:11:22:33:44:55”
Called-Station-Id = “Server_PPPOE1”
NAS-Port-Id = “Auth”
MS-CHAP-Challenge = 0xfd6508a67a8adc23334ba9faaf8e8040
MS-CHAP2-Response = 0x0100d697f60c468cbba0030f4253f1e1102f0000000000000000da2e2eda1b04a7ab7d21bd91055e0585466dea195e50676b
NAS-Identifier = “mikrotik”
NAS-IP-Address = 10.10.10.252
Thu Nov 8 03:19:21 2007 : Debug: Processing the authorize section of radiusd.conf
Thu Nov 8 03:19:21 2007 : Debug: modcall: entering group authorize for request 0
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: calling preprocess (rlm_preprocess) for request 0
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: returned from preprocess (rlm_preprocess) for request 0
Thu Nov 8 03:19:21 2007 : Debug: modcall[authorize]: module “preprocess” returns ok for request 0
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: calling chap (rlm_chap) for request 0
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: returned from chap (rlm_chap) for request 0
Thu Nov 8 03:19:21 2007 : Debug: modcall[authorize]: module “chap” returns noop for request 0
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: calling mschap (rlm_mschap) for request 0
Thu Nov 8 03:19:21 2007 : Debug: rlm_mschap: Found MS-CHAP attributes. Setting ‘Auth-Type = mschap’
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: returned from mschap (rlm_mschap) for request 0
Thu Nov 8 03:19:21 2007 : Debug: modcall[authorize]: module “mschap” returns ok for request 0
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: calling suffix (rlm_realm) for request 0
Thu Nov 8 03:19:21 2007 : Debug: rlm_realm: No ‘@’ in User-Name = “ttt”, looking up realm NULL
Thu Nov 8 03:19:21 2007 : Debug: rlm_realm: No such realm “NULL”
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: returned from suffix (rlm_realm) for request 0
Thu Nov 8 03:19:21 2007 : Debug: modcall[authorize]: module “suffix” returns noop for request 0
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: calling eap (rlm_eap) for request 0
Thu Nov 8 03:19:21 2007 : Debug: rlm_eap: No EAP-Message, not doing EAP
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: returned from eap (rlm_eap) for request 0
Thu Nov 8 03:19:21 2007 : Debug: modcall[authorize]: module “eap” returns noop for request 0
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: calling sql (rlm_sql) for request 0
Thu Nov 8 03:19:21 2007 : Debug: radius_xlat: ‘ttt’
Thu Nov 8 03:19:21 2007 : Debug: rlm_sql (sql): sql_set_user escaped user → ‘ttt’
Thu Nov 8 03:19:21 2007 : Debug: radius_xlat: ‘SELECT id, UserName, Attribute, Value, op FROM radcheck WHERE Username = BINARY ‘ttt’ ORDER BY id’
Thu Nov 8 03:19:21 2007 : Debug: rlm_sql (sql): Reserving sql socket id: 4
Thu Nov 8 03:19:21 2007 : Debug: rlm_sql_mysql: query: SELECT id, UserName, Attribute, Value, op FROM radcheck WHERE Username = BINARY ‘ttt’ ORDER BY id
Thu Nov 8 03:19:21 2007 : Debug: radius_xlat: ‘SELECT radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op FROM radgroupcheck,usergroup WHERE usergroup.Username = BINARY ‘ttt’ AND usergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id’
Thu Nov 8 03:19:21 2007 : Debug: rlm_sql_mysql: query: SELECT radgroupcheck.id,radgroupcheck.GroupName,radgroupcheck.Attribute,radgroupcheck.Value,radgroupcheck.op FROM radgroupcheck,usergroup WHERE usergroup.Username = BINARY ‘ttt’ AND usergroup.GroupName = radgroupcheck.GroupName ORDER BY radgroupcheck.id
Thu Nov 8 03:19:21 2007 : Debug: radius_xlat: ‘SELECT id, UserName, Attribute, Value, op FROM radreply WHERE Username = BINARY ‘ttt’ ORDER BY id’
Thu Nov 8 03:19:21 2007 : Debug: rlm_sql_mysql: query: SELECT id, UserName, Attribute, Value, op FROM radreply WHERE Username = BINARY ‘ttt’ ORDER BY id
Thu Nov 8 03:19:21 2007 : Debug: radius_xlat: ‘SELECT radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op FROM radgroupreply,usergroup WHERE usergroup.Username = BINARY ‘ttt’ AND usergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id’
Thu Nov 8 03:19:21 2007 : Debug: rlm_sql_mysql: query: SELECT radgroupreply.id,radgroupreply.GroupName,radgroupreply.Attribute,radgroupreply.Value,radgroupreply.op FROM radgroupreply,usergroup WHERE usergroup.Username = BINARY ‘ttt’ AND usergroup.GroupName = radgroupreply.GroupName ORDER BY radgroupreply.id
Thu Nov 8 03:19:21 2007 : Debug: rlm_sql (sql): Released sql socket id: 4
Thu Nov 8 03:19:21 2007 : Info: rlm_sql (sql): No matching entry in the database for request from user [ttt]
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: returned from sql (rlm_sql) for request 0
Thu Nov 8 03:19:21 2007 : Debug: modcall[authorize]: module “sql” returns notfound for request 0
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: calling noresetcounter (rlm_sqlcounter) for request 0
Thu Nov 8 03:19:21 2007 : Debug: rlm_sqlcounter: Entering module authorize code
Thu Nov 8 03:19:21 2007 : Debug: rlm_sqlcounter: Could not find Check item value pair
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: returned from noresetcounter (rlm_sqlcounter) for request 0
Thu Nov 8 03:19:21 2007 : Debug: modcall[authorize]: module “noresetcounter” returns noop for request 0
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: calling monthlycounter (rlm_sqlcounter) for request 0
Thu Nov 8 03:19:21 2007 : Debug: rlm_sqlcounter: Entering module authorize code
Thu Nov 8 03:19:21 2007 : Debug: rlm_sqlcounter: Could not find Check item value pair
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authorize]: returned from monthlycounter (rlm_sqlcounter) for request 0
Thu Nov 8 03:19:21 2007 : Debug: modcall[authorize]: module “monthlycounter” returns noop for request 0
Thu Nov 8 03:19:21 2007 : Debug: modcall: leaving group authorize (returns ok) for request 0
Thu Nov 8 03:19:21 2007 : Debug: rad_check_password: Found Auth-Type MS-CHAP
Thu Nov 8 03:19:21 2007 : Debug: auth: type “MS-CHAP”
Thu Nov 8 03:19:21 2007 : Debug: Processing the authenticate section of radiusd.conf
Thu Nov 8 03:19:21 2007 : Debug: modcall: entering group MS-CHAP for request 0
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authenticate]: calling mschap (rlm_mschap) for request 0
Thu Nov 8 03:19:21 2007 : Debug: rlm_mschap: No User-Password configured. Cannot create LM-Password.
Thu Nov 8 03:19:21 2007 : Debug: rlm_mschap: No User-Password configured. Cannot create NT-Password.
Thu Nov 8 03:19:21 2007 : Debug: rlm_mschap: Told to do MS-CHAPv2 for ttt with NT-Password
Thu Nov 8 03:19:21 2007 : Debug: rlm_mschap: FAILED: No NT/LM-Password. Cannot perform authentication.
Thu Nov 8 03:19:21 2007 : Debug: rlm_mschap: FAILED: MS-CHAP2-Response is incorrect
Thu Nov 8 03:19:21 2007 : Debug: modsingle[authenticate]: returned from mschap (rlm_mschap) for request 0
Thu Nov 8 03:19:21 2007 : Debug: modcall[authenticate]: module “mschap” returns reject for request 0
Thu Nov 8 03:19:21 2007 : Debug: modcall: leaving group MS-CHAP (returns reject) for request 0
Thu Nov 8 03:19:21 2007 : Debug: auth: Failed to validate the user.
Thu Nov 8 03:19:21 2007 : Debug: Delaying request 0 for 1 seconds
Thu Nov 8 03:19:21 2007 : Debug: Finished request 0
Thu Nov 8 03:19:21 2007 : Debug: Going to the next request
Thu Nov 8 03:19:21 2007 : Debug: — Walking the entire request list —
Thu Nov 8 03:19:21 2007 : Debug: Waking up in 1 seconds…
rad_recv: Access-Request packet from host 10.10.10.252:3859, id=178, length=194
Sending Access-Reject of id 178 to 10.10.10.252 port 3859
Thu Nov 8 03:19:21 2007 : Debug: rl_next: returning NULL
Thu Nov 8 03:19:21 2007 : Debug: Waking up in 1 seconds…
Thu Nov 8 03:19:22 2007 : Debug: — Walking the entire request list —
Thu Nov 8 03:19:22 2007 : Debug: Waking up in 5 seconds…
Why the radius call always —> Called-Station-Id = “Server_PPPOE1” 
I had try with different OP Values (“=” or “:=”) but still not work…
On windows i can set service name on pppoe connection proprieties…but i work with Osbridge CPE (Not support PPPoE Service name)…
help me pls !!!