Can't login through user-manager and pppoe ( SOLVED !)

hi,

I’ve a pppoe server working and when I login
with a “/ppp secret” user , (from windows XP), I’ve
no problems

Now, I want to create users throught “user-manager”, and
for this propose I’ve done next steps:

(user man and pppoe server in same box!)


[admin@Mikrotik] /ppp aaa> print 
      use-radius: yes
      accounting: yes
  interim-update: 0s

[/color]

And next:


[admin@Mikrotik] /radius> print
Flags: X - disabled

SERVICE CALLED-ID DOMAIN ADDRESS SECRET

0 ppp 127.0.0.1 123456 >

and this:


/interface pppoe-server server
add authentication=pap,chap default-profile=macus_sin_bw disabled=no
interface=LAN-VMNET keepalive-timeout=10 max-mru=1480 max-mtu=1480
max-sessions=0 mrru=disabled one-session-per-host=yes
service-name=“service1” >


And this is my usermanager configuration:



/tool user-manager credit
add comment=“” extend-price=0 full-price=0 name=“credito” subscriber=admin
time=2h
/tool user-manager customer
add comment=“” disabled=no login=“admin” parent=admin password=“admin”
permissions=owner subscriber=admin time-zone=+00:00
/tool user-manager router
add comment=“” disabled=no ip-address=192.168.3.1
log=auth-ok,auth-fail,acct-fail name=“vm_ware_dell” shared-secret=“123456”
subscriber=admin
/tool user-manager user
add comment=“” disabled=no name=“m1m1” password=“m1m1” subscriber=admin
add comment=“” disabled=no name=“m1” password=“m1” subscriber=admin >


But, everytime I try to log from a windows XP session (with a usermanager created user),
i get next error (from LOG):

pppoe,debug,packet LAN-VMNET: rcvd PADI from 00:50:56:C0:00:01 in 18-Aug 16:57:42.29 from 192.168.3.1
pppoe,debug,packet     session-id=0x0000 in 18-Aug 16:57:42.29 from 192.168.3.1
pppoe,debug,packet     service-name= in 18-Aug 16:57:42.31 from 192.168.3.1
pppoe,debug,packet     host-uniq=14 00 00 00 27 00 00 00 in 18-Aug 16:57:42.32 from 192.168.3.1
pppoe,debug,packet LAN-VMNET: sent PADO to 00:50:56:C0:00:01 in 18-Aug 16:57:42.34 from 192.168.3.1
pppoe,debug,packet     session-id=0x0000 in 18-Aug 16:57:42.34 from 192.168.3.1
pppoe,debug,packet     host-uniq=14 00 00 00 27 00 00 00 in 18-Aug 16:57:42.35 from 192.168.3.1
pppoe,debug,packet     service-name= in 18-Aug 16:57:42.35 from 192.168.3.1
pppoe,debug,packet     ac-name=VM_Ware_Dell in 18-Aug 16:57:42.37 from 192.168.3.1
pppoe,debug,packet     service-name=service1 in 18-Aug 16:57:42.39 from 192.168.3.1
pppoe,debug,packet LAN-VMNET: rcvd PADR from 00:50:56:C0:00:01 in 18-Aug 16:57:42.39 from 192.168.3.1
pppoe,debug,packet     session-id=0x0000 in 18-Aug 16:57:42.39 from 192.168.3.1
pppoe,debug,packet     service-name= in 18-Aug 16:57:42.40 from 192.168.3.1
pppoe,debug,packet     host-uniq=14 00 00 00 28 00 00 00 in 18-Aug 16:57:42.40 from 192.168.3.1
pppoe,info PPPoE connection established from 00:50:56:C0:00:01 in 18-Aug 16:57:42.42 from 192.168.3.1
pppoe,ppp,info <pppoe-0>: waiting for call... in 18-Aug 16:57:42.42 from 192.168.3.1
pppoe,ppp,debug <0015>: LCP lowerup in 18-Aug 16:57:42.43 from 192.168.3.1
pppoe,ppp,debug <0015>: LCP open in 18-Aug 16:57:42.43 from 192.168.3.1
pppoe,debug,packet LAN-VMNET: sent PADS to 00:50:56:C0:00:01 in 18-Aug 16:57:42.45 from 192.168.3.1
pppoe,debug,packet     session-id=0x1500 in 18-Aug 16:57:42.45 from 192.168.3.1
pppoe,debug,packet     host-uniq=14 00 00 00 28 00 00 00 in 18-Aug 16:57:42.46 from 192.168.3.1
pppoe,debug,packet     service-name= in 18-Aug 16:57:42.46 from 192.168.3.1
pppoe,ppp,debug,packet  <0015>: rcvd LCP ConfReq id=0x0 in 18-Aug 16:57:42.48 from 192.168.3.1
pppoe,ppp,debug,packet    <mru 1480> in 18-Aug 16:57:42.48 from 192.168.3.1
pppoe,ppp,debug,packet    <magic 0x4779b828> in 18-Aug 16:57:42.48 from 192.168.3.1
pppoe,ppp,debug,packet    <callback 0x06> in 18-Aug 16:57:42.50 from 192.168.3.1
pppoe,ppp,debug,packet  <0015>: sent LCP ConfReq id=0x1 in 18-Aug 16:57:42.50 from 192.168.3.1
pppoe,ppp,debug,packet    <mru 1480> in 18-Aug 16:57:42.50 from 192.168.3.1
pppoe,ppp,debug,packet    <magic 0x635890fb> in 18-Aug 16:57:42.50 from 192.168.3.1
pppoe,ppp,debug,packet    <auth  mschap2> in 18-Aug 16:57:42.51 from 192.168.3.1
pppoe,ppp,debug,packet  <0015>: sent LCP ConfAck id=0x0 in 18-Aug 16:57:42.51 from 192.168.3.1
pppoe,ppp,debug,packet    <mru 1480> in 18-Aug 16:57:42.51 from 192.168.3.1
pppoe,ppp,debug,packet    <magic 0x4779b828> in 18-Aug 16:57:42.51 from 192.168.3.1
pppoe,ppp,debug,packet    <callback 0x06> in 18-Aug 16:57:42.53 from 192.168.3.1
pppoe,ppp,debug,packet  <0015>: rcvd LCP ConfAck id=0x1 in 18-Aug 16:57:42.53 from 192.168.3.1
pppoe,ppp,debug,packet    <mru 1480> in 18-Aug 16:57:42.53 from 192.168.3.1
pppoe,ppp,debug,packet    <magic 0x635890fb> in 18-Aug 16:57:42.54 from 192.168.3.1
pppoe,ppp,debug,packet    <auth  mschap2> in 18-Aug 16:57:42.54 from 192.168.3.1
pppoe,ppp,debug <0015>: LCP opened in 18-Aug 16:57:42.54 from 192.168.3.1
pppoe,ppp,debug,packet  <0015>: sent CHAP Challenge id=0x1 in 18-Aug 16:57:42.54 from 192.168.3.1
pppoe,ppp,debug,packet     <challenge len=10> in 18-Aug 16:57:42.56 from 192.168.3.1
pppoe,ppp,debug,packet     <name VM_Ware_Dell> in 18-Aug 16:57:42.56 from 192.168.3.1
pppoe,ppp,debug,packet  <0015>: rcvd LCP Ident id=0x1 in 18-Aug 16:57:42.56 from 192.168.3.1
pppoe,ppp,debug,packet     <magic 0x4779b828> in 18-Aug 16:57:42.57 from 192.168.3.1
pppoe,ppp,debug,packet     MSRASV5.10 in 18-Aug 16:57:42.57 from 192.168.3.1
pppoe,ppp,debug,packet  <0015>: rcvd LCP Ident id=0x2 in 18-Aug 16:57:42.57 from 192.168.3.1
pppoe,ppp,debug,packet     <magic 0x4779b828> in 18-Aug 16:57:42.57 from 192.168.3.1
pppoe,ppp,debug,packet     MSRAS-0-LUNA in 18-Aug 16:57:42.59 from 192.168.3.1
pppoe,ppp,debug,packet  <0015>: rcvd CHAP Response id=0x1 in 18-Aug 16:57:42.59 from 192.168.3.1
pppoe,ppp,debug,packet     <response len=31> in 18-Aug 16:57:42.59 from 192.168.3.1
pppoe,ppp,debug,packet     <name m1> in 18-Aug 16:57:42.60 from 192.168.3.1
radius,debug new request 1b:27 code=Access-Request service=ppp called-id=service1 in 18-Aug 16:57:42.60 from 192.168.3.1
radius,debug sending 1b:27 to 127.0.0.1:1812 in 18-Aug 16:57:42.60 from 192.168.3.1
radius,debug,packet sending Access-Request with id 28 to 127.0.0.1:1812 in 18-Aug 16:57:42.60 from 192.168.3.1
radius,debug,packet     Signature = 0x8940603f463468a510fbb5ec69506a69 in 18-Aug 16:57:42.62 from 192.168.3.1
radius,debug,packet     Service-Type = 2 in 18-Aug 16:57:42.62 from 192.168.3.1
radius,debug,packet     Framed-Protocol = 1 in 18-Aug 16:57:42.62 from 192.168.3.1
radius,debug,packet     NAS-Port = 24 in 18-Aug 16:57:42.64 from 192.168.3.1
radius,debug,packet     NAS-Port-Type = 15 in 18-Aug 16:57:42.64 from 192.168.3.1
radius,debug,packet     User-Name = "m1" in 18-Aug 16:57:42.64 from 192.168.3.1
radius,debug,packet     Calling-Station-Id = "00:50:56:C0:00:01" in 18-Aug 16:57:42.64 from 192.168.3.1
radius,debug,packet     Called-Station-Id = "service1" in 18-Aug 16:57:42.65 from 192.168.3.1
radius,debug,packet     NAS-Port-Id = "LAN-VMNET" in 18-Aug 16:57:42.65 from 192.168.3.1
radius,debug,packet     MS-CHAP-Challenge = 0x1f507f5fadd93f76238ebf4732cedea8 in 18-Aug 16:57:42.65 from 192.168.3.1
radius,debug,packet     MS-CHAP2-Response = 0x0100fa4bb9b4f39c08fccc024e91576c in 18-Aug 16:57:42.65 from 192.168.3.1
radius,debug,packet       80f800000000000000005158591e0454 in 18-Aug 16:57:42.67 from 192.168.3.1
radius,debug,packet       15da69131a597dfe9589f0c4728f90ed in 18-Aug 16:57:42.67 from 192.168.3.1
radius,debug,packet       d04a in 18-Aug 16:57:42.67 from 192.168.3.1
radius,debug,packet     NAS-Identifier = "VM_Ware_Dell" in 18-Aug 16:57:42.68 from 192.168.3.1
radius,debug,packet     NAS-IP-Address = 127.0.0.1 in 18-Aug 16:57:42.68 from 192.168.3.1
manager,debug received remote request from 127.0.0.1:32772 with unknown address, dropping in 18-Aug 16:57:42.68 from 192.168.3.1
radius,debug resending 1b:27 in 18-Aug 16:57:42.68 from 192.168.3.1
radius,debug,packet sending Access-Request with id 28 to 127.0.0.1:1812 in 18-Aug 16:57:42.70 from 192.168.3.1
radius,debug,packet     Signature = 0x8940603f463468a510fbb5ec69506a69 in 18-Aug 16:57:42.70 from 192.168.3.1
radius,debug,packet     Service-Type = 2 in 18-Aug 16:57:42.70 from 192.168.3.1
radius,debug,packet     Framed-Protocol = 1 in 18-Aug 16:57:42.70 from 192.168.3.1
radius,debug,packet     NAS-Port = 24 in 18-Aug 16:57:42.71 from 192.168.3.1
radius,debug,packet     NAS-Port-Type = 15 in 18-Aug 16:57:42.71 from 192.168.3.1
radius,debug,packet     User-Name = "m1" in 18-Aug 16:57:42.71 from 192.168.3.1
radius,debug,packet     Calling-Station-Id = "00:50:56:C0:00:01" in 18-Aug 16:57:42.73 from 192.168.3.1
radius,debug,packet     Called-Station-Id = "service1" in 18-Aug 16:57:42.73 from 192.168.3.1
radius,debug,packet     NAS-Port-Id = "LAN-VMNET" in 18-Aug 16:57:42.73 from 192.168.3.1
radius,debug,packet     MS-CHAP-Challenge = 0x1f507f5fadd93f76238ebf4732cedea8 in 18-Aug 16:57:42.73 from 192.168.3.1
radius,debug,packet     MS-CHAP2-Response = 0x0100fa4bb9b4f39c08fccc024e91576c in 18-Aug 16:57:42.75 from 192.168.3.1
radius,debug,packet       80f800000000000000005158591e0454 in 18-Aug 16:57:42.75 from 192.168.3.1
radius,debug,packet       15da69131a597dfe9589f0c4728f90ed in 18-Aug 16:57:42.75 from 192.168.3.1
radius,debug,packet       d04a in 18-Aug 16:57:42.76 from 192.168.3.1
radius,debug,packet     NAS-Identifier = "VM_Ware_Dell" in 18-Aug 16:57:42.76 from 192.168.3.1
radius,debug,packet     NAS-IP-Address = 127.0.0.1 in 18-Aug 16:57:42.76 from 192.168.3.1



manager,debug received remote request from 127.0.0.1:32772 with unknown address, dropping in 18-Aug 16:57:42.76 from 192.168.3.1



radius,debug resending 1b:27 in 18-Aug 16:57:42.96 from 192.168.3.1
radius,debug,packet sending Access-Request with id 28 to 127.0.0.1:1812 in 18-Aug 16:57:42.96 from 192.168.3.1
radius,debug,packet     Signature = 0x8940603f463468a510fbb5ec69506a69 in 18-Aug 16:57:42.96 from 192.168.3.1
radius,debug,packet     Service-Type = 2 in 18-Aug 16:57:42.96 from 192.168.3.1
radius,debug,packet     Framed-Protocol = 1 in 18-Aug 16:57:42.98 from 192.168.3.1
radius,debug,packet     NAS-Port = 24 in 18-Aug 16:57:42.98 from 192.168.3.1
radius,debug,packet     NAS-Port-Type = 15 in 18-Aug 16:57:42.98 from 192.168.3.1
radius,debug,packet     User-Name = "m1" in 18-Aug 16:57:42.98 from 192.168.3.1

This is what I don’t understand :

manager,debug received remote request from 127.0.0.1:32772 with unknown address, dropping in 18-Aug 16:57:42.76 from 192.168.3.1

what is this ?

thank you.

this error :

manager,debug received remote request from 127.0.0.1:32772 with unknown address, dropping in 18-Aug 16:57:42.76 from 192.168.3.1

was solved putting correct IP ROUTER CLIENT under USERMANAGER:

[admin@VM_Ware_Dell] /tool user-manager router> print
Flags: X - disabled
0 subscriber=admin name=“vm_ware_dell” ip-address=192.168.3.1
shared-secret=“123456” log=auth-ok,auth-fail,acct-fail
[admin@VM_Ware_Dell] /tool user-manager router> set 0 ip-address=127.0.0.1

Thank you.