|
ru.unix.bsd
- RU.UNIX.BSD ------------------------------------------------------------------
From : Rick Atreides 2:5020/400 21 Oct 2005 16:34:01
To : All
Subject : mpd + radius + freenibs. Проблема запустить пользователя.
--------------------------------------------------------------------------------
FreeBSD 5.3 (также проверялось на 5.4 release и RELENG_5_4)
mpd 3.18_2
255 интерфейсов.
Идёт большой поток подключений, при этом пользователь не заходит. У
пользователя идёт подключение, поверка пароля, регистрация в сети.
Отключение происходит как на проверке пароля, так и на регистрации.
По логу - mpd посылает radius запрос, радиус авторизует, посылает ответ,
mpd его получает, и послыает запрос опять. В этом собственно глюк и
заключается.
Машина
CPU: Intel(R) Pentium(R) 4 CPU 1.60GHz (1603.64-MHz 686-class CPU)
real memory = 469696512 (447 MB)
xl1: <3Com 3c905-TX Fast Etherlink XL> (внутреняя)
Эта-же конфигурация (dump системы + смена ip) работает при 65
одновременных сессий на машине рядом.
В основном проявляется на большой загрузке
Hапример висит 130 сессий, перегружаем машину, имеем примерно 100
попыток соединиться, подключается 5-10 пользователей, и всё, остальные -
долбятся и долбятся. Иногда, через некоторое время всё "рассасывается" и
пользователи конектятся нормально (вплоть до 250 одновременных
соединений).
Интересует.
1) Может кто с такой хренью сталкивался и может подсказать решение проблемы.
2) Как локализовать проблему (это freenibs или mpd), как получить более
подробный лог, в котором будет явно сказано типа "Error нету того-то, не
хватает того-то и т.д."
3) Hу и в догонку, почти всегда, при перезапуске mpd как mpd -k -b он
опускает существующий mpd, запускает новый, и где-то на 220-230 ng
интерфейсе компьютер виснет наглухо в messgaes при этом ничего не пишет.
В mpd.log
-----------pptp27
Oct 21 15:01:12 unet mpd: [pptp27] IPCP: Down event
Oct 21 15:01:12 unet mpd: [pptp27] IPCP: state change Opened --> Starting
Oct 21 15:01:12 unet mpd: [pptp27] IPCP: LayerDown
Oct 21 15:01:12 unet mpd: [pptp27] IFACE: Down event
Oct 21 15:01:12 unet mpd: [pptp27] exec: /sbin/route delete 172.16.0.1
-iface lo0
Oct 21 15:01:12 unet mpd: [pptp27] exec: command returned 256
Oct 21 15:01:12 unet mpd: [pptp27] exec: /sbin/ifconfig ng27 down delete
-link0
Oct 21 15:01:12 unet mpd: [pptp27] IFACE: Close event
Oct 21 15:01:12 unet mpd: [pptp27] IPCP: Close event
Oct 21 15:01:12 unet mpd: [pptp27] IPCP: state change Starting --> Initial
Oct 21 15:01:12 unet mpd: [pptp27] IPCP: LayerFinish
Oct 21 15:01:17 unet mpd: [pptp27] bundle: CLOSE event in state OPENED
Oct 21 15:01:17 unet mpd: [pptp27] closing link "pptp27"...
Oct 21 15:02:29 unet mpd: [pptp27] ppp node is "mpd697-pptp27"
Oct 21 15:02:29 unet mpd: [pptp27] using interface ng27
Oct 21 15:06:46 unet mpd: [pptp27] IFACE: Open event
Oct 21 15:06:46 unet mpd: [pptp27] IPCP: Open event
Oct 21 15:06:46 unet mpd: [pptp27] IPCP: state change Initial --> Starting
Oct 21 15:06:46 unet mpd: [pptp27] IPCP: LayerStart
Oct 21 15:06:46 unet mpd: [pptp27] IPCP: Open event
Oct 21 15:06:47 unet mpd: [pptp27] bundle: OPEN event in state CLOSED
Oct 21 15:06:47 unet mpd: [pptp27] opening link "pptp27"...
Oct 21 15:06:49 unet mpd: [pptp27] link: OPEN event
Oct 21 15:06:49 unet mpd: [pptp27] LCP: Open event
Oct 21 15:06:49 unet mpd: [pptp27] LCP: state change Initial --> Starting
Oct 21 15:06:49 unet mpd: [pptp27] LCP: LayerStart
Oct 21 15:06:50 unet mpd: [pptp27] device: OPEN event in state DOWN
Oct 21 15:06:50 unet mpd: [pptp27] attaching to peer's outgoing call
Oct 21 15:06:50 unet mpd: [pptp27] device is now in state OPENING
Oct 21 15:06:51 unet mpd: [pptp27] device: UP event in state OPENING
Oct 21 15:06:51 unet mpd: [pptp27] device is now in state UP
Oct 21 15:06:51 unet mpd: [pptp27] rec'd proto LCP while dead
Oct 21 15:06:52 unet mpd: [pptp27] link: UP event
Oct 21 15:06:52 unet mpd: [pptp27] link: origination is remote
Oct 21 15:06:52 unet mpd: [pptp27] LCP: Up event
Oct 21 15:06:52 unet mpd: [pptp27] LCP: state change Starting --> Req-Sent
Oct 21 15:06:52 unet mpd: [pptp27] LCP: phase shift DEAD --> ESTABLISH
Oct 21 15:06:52 unet mpd: [pptp27] LCP: SendConfigReq #1
Oct 21 15:06:52 unet mpd: ACFCOMP
Oct 21 15:06:52 unet mpd: PROTOCOMP
Oct 21 15:06:52 unet mpd: MRU 1400
Oct 21 15:06:52 unet mpd: MAGICNUM c013857b
Oct 21 15:06:52 unet mpd: MRU 1400
Oct 21 15:06:52 unet mpd: MAGICNUM c013857b
Oct 21 15:06:52 unet mpd: AUTHPROTO CHAP MSOFTv2
Oct 21 15:06:52 unet mpd: MP MRRU 1600
Oct 21 15:06:52 unet mpd: MP SHORTSEQ
Oct 21 15:06:52 unet mpd: ENDPOINTDISC [802.1] 00 60 98 ef 0d 86
Oct 21 15:06:52 unet mpd: [pptp27] LCP: rec'd Configure Request #1 link
0 (Req-Sent)
Oct 21 15:06:52 unet mpd: MRU 1400
Oct 21 15:06:52 unet mpd: MAGICNUM 1d42670c
Oct 21 15:06:52 unet mpd: PROTOCOMP
Oct 21 15:06:52 unet mpd: ACFCOMP
Oct 21 15:06:52 unet mpd: CALLBACK
Oct 21 15:06:52 unet mpd: Not supported
Oct 21 15:06:52 unet mpd: [pptp27] LCP: SendConfigRej #1
Oct 21 15:06:52 unet mpd: CALLBACK
Oct 21 15:06:52 unet mpd: pptp23-0: ignoring SetLinkInfo
Oct 21 15:06:53 unet mpd: [pptp27] LCP: rec'd Configure Reject #1 link 0
(Req-Sent)
Oct 21 15:06:53 unet mpd: MP MRRU 1600
Oct 21 15:06:53 unet mpd: MP SHORTSEQ
Oct 21 15:06:53 unet mpd: ENDPOINTDISC [802.1] 00 60 98 ef 0d 86
Oct 21 15:06:53 unet mpd: [pptp27] LCP: SendConfigReq #2
Oct 21 15:06:53 unet mpd: ACFCOMP
Oct 21 15:06:53 unet mpd: PROTOCOMP
Oct 21 15:06:53 unet mpd: MRU 1400
Oct 21 15:06:53 unet mpd: MAGICNUM c013857b
Oct 21 15:06:53 unet mpd: AUTHPROTO CHAP MSOFTv2
Oct 21 15:06:53 unet mpd: [pptp27] LCP: rec'd Configure Request #2 link
0 (Req-Sent)
Oct 21 15:06:53 unet mpd: MRU 1400
Oct 21 15:06:53 unet mpd: MAGICNUM 1d42670c
Oct 21 15:06:53 unet mpd: PROTOCOMP
Oct 21 15:06:53 unet mpd: ACFCOMP
Oct 21 15:06:53 unet mpd: [pptp27] LCP: SendConfigAck #2
Oct 21 15:06:53 unet mpd: MRU 1400
Oct 21 15:06:53 unet mpd: MAGICNUM 1d42670c
Oct 21 15:06:53 unet mpd: PROTOCOMP
Oct 21 15:06:53 unet mpd: ACFCOMP
Oct 21 15:06:53 unet mpd: [pptp27] LCP: state change Req-Sent --> Ack-Sent
Oct 21 15:06:54 unet mpd: [pptp27] LCP: rec'd Configure Ack #2 link 0
(Ack-Sent)
Oct 21 15:06:54 unet mpd: ACFCOMP
Oct 21 15:06:54 unet mpd: PROTOCOMP
Oct 21 15:06:54 unet mpd: MRU 1400
Oct 21 15:06:54 unet mpd: MAGICNUM c013857b
Oct 21 15:06:54 unet mpd: AUTHPROTO CHAP MSOFTv2
Oct 21 15:06:54 unet mpd: MAGICNUM c013857b
Oct 21 15:06:54 unet mpd: AUTHPROTO CHAP MSOFTv2
Oct 21 15:06:54 unet mpd: [pptp27] LCP: state change Ack-Sent --> Opened
Oct 21 15:06:54 unet mpd: [pptp27] LCP: phase shift ESTABLISH -->
AUTHENTICATE
Oct 21 15:06:54 unet mpd: [pptp27] LCP: auth: peer wants nothing, I want
CHAP
Oct 21 15:06:54 unet mpd: [pptp27] CHAP: sending CHALLENGE
Oct 21 15:06:54 unet mpd: [pptp27] LCP: LayerUp
Oct 21 15:06:54 unet mpd: [pptp27] LCP: rec'd Ident #3 link 0 (Opened)
Oct 21 15:06:54 unet mpd: MESG: MSRASV5.20
Oct 21 15:06:55 unet mpd: [pptp27] LCP: rec'd Ident #4 link 0 (Opened)
Oct 21 15:06:55 unet mpd: MESG: MSRAS-0-NAGGOHOK
Oct 21 15:06:56 unet mpd: [pptp27] CHAP: sending CHALLENGE
Oct 21 15:06:56 unet mpd: [pptp27] CHAP: rec'd RESPONSE #1
Oct 21 15:06:56 unet mpd: Name: "nAggOHOK"
Oct 21 15:06:56 unet mpd: [pptp27] RADIUS: using /etc/radius.conf
Oct 21 15:06:56 unet mpd: [pptp27] RADIUS: RadiusAddServer Adding 127.0.0.1
Oct 21 15:06:56 unet mpd: [pptp27] RADIUS: RadiusPutAuth: RADIUS_CHAP
(MSOFTv2) peer name: nAggOHOK
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusSendRequest:
RAD_ACCESS_ACCEPT for user nAggOHOK
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_IP_ADDRESS: 172.16.50.81
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_ACCT_INTERIM_INTERVAL: 60
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_PROTOCOL: 2
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_PROTOCOL: 1
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_COMPRESSION: 1
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_CHAP2_SUCCESS: S=D63394FFA69CAD6B77A5EEC50F7689A
7765F133D
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 26
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_RECV_KEY
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 17
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_SEND_KEY
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 16
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_ENCRYPTION_POLICY: 1 (Allowed)
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 7
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_ENCRYPTION_TYPES: 6 (40 128 bit)
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 8
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusSetAuth: Trying to use
IP-address from radius-server
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusSetAuth: using this IP:
172.16.50.81
Oct 21 15:06:57 unet mpd: Response is valid
Oct 21 15:06:57 unet mpd: [pptp27] CHAP: sending SUCCESS
Oct 21 15:06:57 unet mpd: [pptp27] LCP: authorization successful
Oct 21 15:06:57 unet mpd: [pptp27] LCP: phase shift AUTHENTICATE --> NETWORK
Oct 21 15:06:57 unet mpd: [pptp27] setting interface ng27 MTU to 1400 bytes
Oct 21 15:06:57 unet mpd: [pptp27] up: 1 link, total bandwidth 64000 bps
Oct 21 15:06:57 unet mpd: [pptp27] IPCP: Up event
Oct 21 15:06:57 unet mpd: [pptp27] up: 1 link, total bandwidth 64000 bps
Oct 21 15:06:57 unet mpd: [pptp27] IPCP: Up event
Oct 21 15:06:57 unet mpd: [pptp27] IPCP: state change Starting --> Req-Sent
Oct 21 15:06:57 unet mpd: [pptp27] IPCP: SendConfigReq #1
Oct 21 15:06:57 unet mpd: IPADDR 172.16.0.1
Oct 21 15:06:57 unet mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Oct 21 15:06:57 unet mpd: [pptp27] CCP: Open event
Oct 21 15:06:57 unet mpd: [pptp27] CCP: state change Initial --> Starting
Oct 21 15:06:57 unet mpd: [pptp27] CCP: LayerStart
Oct 21 15:06:57 unet mpd: [pptp27] CCP: Up event
Oct 21 15:06:57 unet mpd: [pptp27] CCP: state change Starting --> Req-Sent
Oct 21 15:06:57 unet mpd: [pptp27] CCP: SendConfigReq #1
Oct 21 15:06:57 unet mpd: [pptp27] CCP: Checking whether 40 bits are
enabled -> yes (RADIUS)
Oct 21 15:06:57 unet mpd: [pptp27] CCP: Checking whether 56 bits are
enabled -> no (RADIUS)
Oct 21 15:06:57 unet mpd: [pptp27] CCP: Checking whether 128 bits are
enabled -> yes (RADIUS)
Oct 21 15:06:57 unet mpd: MPPC
Oct 21 15:06:57 unet mpd: 0x00000060: MPPE, 40 bit, 128 bit
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusAccount for: nAggOHOK
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: using /etc/radius.conf
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusAddServer Adding 127.0.0.1
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusAccount: Sending
accounting data (Type: 1)
Oct 21 15:06:57 unet mpd: [pptp27] RADIUS: RadiusSendRequest:
RAD_ACCOUNTING_RESPONSE for user nAggOHOK
Oct 21 15:06:59 unet mpd: [pptp27] IPCP: SendConfigReq #2
Oct 21 15:06:59 unet mpd: IPADDR 172.16.0.1
Oct 21 15:06:59 unet mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Oct 21 15:06:59 unet mpd: [pptp27] CCP: SendConfigReq #2
Oct 21 15:06:59 unet mpd: [pptp27] CCP: Checking whether 40 bits are
enabled -> yes (RADIUS)
Oct 21 15:06:59 unet mpd: [pptp27] CCP: Checking whether 56 bits are
enabled -> no (RADIUS)
Oct 21 15:06:59 unet mpd: [pptp27] CCP: Checking whether 128 bits are
enabled -> yes (RADIUS)
Oct 21 15:06:59 unet mpd: MPPC
Oct 21 15:06:59 unet mpd: 0x00000060: MPPE, 40 bit, 128 bit
Oct 21 15:06:59 unet mpd: [pptp27] CHAP: rec'd RESPONSE #2
Oct 21 15:06:59 unet mpd: Not expected, but that's OK
Oct 21 15:06:59 unet mpd: Name: "nAggOHOK"
Oct 21 15:06:59 unet mpd: [pptp27] RADIUS: using /etc/radius.conf
Oct 21 15:06:59 unet mpd: [pptp27] RADIUS: RadiusAddServer Adding 127.0.0.1
Oct 21 15:06:59 unet mpd: [pptp27] RADIUS: RadiusPutAuth: RADIUS_CHAP
(MSOFTv2) peer name: nAggOHOK
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusSendRequest:
RAD_ACCESS_ACCEPT for user nAggOHOK
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_IP_ADDRESS: 172.16.50.81
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_ACCT_INTERIM_INTERVAL: 60
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_PROTOCOL: 2
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_PROTOCOL: 1
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_COMPRESSION: 1
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_PROTOCOL: 1
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_COMPRESSION: 1
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_CHAP2_SUCCESS: S=FA7F791265563E617901BF930F3825A
C8B5453FC
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 26
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_RECV_KEY
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 17
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_SEND_KEY
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 16
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_ENCRYPTION_POLICY: 1 (Allowed)
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 7
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_ENCRYPTION_TYPES: 6 (40 128 bit)
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 8
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusSetAuth: Trying to use
IP-address from radius-server
Oct 21 15:07:00 unet mpd: [pptp27] RADIUS: RadiusSetAuth: using this IP:
172.16.50.81
Oct 21 15:07:00 unet mpd: Response is valid
Oct 21 15:07:00 unet mpd: [pptp27] CHAP: sending SUCCESS
Oct 21 15:07:00 unet mpd: [pptp27] LCP: authorization successful
Oct 21 15:07:03 unet mpd: [pptp27] IPCP: SendConfigReq #3
Oct 21 15:07:03 unet mpd: IPADDR 172.16.0.1
Oct 21 15:07:03 unet mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Oct 21 15:07:03 unet mpd: [pptp27] CCP: SendConfigReq #3
Oct 21 15:07:03 unet mpd: [pptp27] CCP: Checking whether 40 bits are
enabled -> yes (RADIUS)
Oct 21 15:07:03 unet mpd: [pptp27] CCP: Checking whether 56 bits are
enabled -> no (RADIUS)
Oct 21 15:07:03 unet mpd: [pptp27] CCP: Checking whether 128 bits are
enabled -> yes (RADIUS)
Oct 21 15:07:03 unet mpd: MPPC
Oct 21 15:07:03 unet mpd: 0x00000060: MPPE, 40 bit, 128 bit
Oct 21 15:07:03 unet mpd: [pptp27] CHAP: rec'd RESPONSE #2
Oct 21 15:07:03 unet mpd: Not expected, but that's OK
Oct 21 15:07:03 unet mpd: Name: "nAggOHOK"
Oct 21 15:07:03 unet mpd: [pptp27] RADIUS: using /etc/radius.conf
Oct 21 15:07:03 unet mpd: [pptp27] RADIUS: RadiusAddServer Adding 127.0.0.1
Oct 21 15:07:03 unet mpd: [pptp27] RADIUS: RadiusPutAuth: RADIUS_CHAP
(MSOFTv2) peer name: nAggOHOK
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusSendRequest:
RAD_ACCESS_ACCEPT for user nAggOHOK
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_IP_ADDRESS: 172.16.50.81
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_ACCT_INTERIM_INTERVAL: 60
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_PROTOCOL: 2
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_PROTOCOL: 1
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_FRAMED_COMPRESSION: 1
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_CHAP2_SUCCESS: S=FA7F791265563E617901BF930F3825A
C8B5453FC
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 26
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_RECV_KEY
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 26
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_RECV_KEY
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 17
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_SEND_KEY
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 16
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_ENCRYPTION_POLICY: 1 (Allowed)
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 7
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams:
RAD_MICROSOFT_MS_MPPE_ENCRYPTION_TYPES: 6 (40 128 bit)
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusGetParams: Dropping MPD
vendor specific attribute: 8
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusSetAuth: Trying to use
IP-address from radius-server
Oct 21 15:07:04 unet mpd: [pptp27] RADIUS: RadiusSetAuth: using this IP:
172.16.50.81
Oct 21 15:07:04 unet mpd: Response is valid
Oct 21 15:07:04 unet mpd: [pptp27] CHAP: sending SUCCESS
Oct 21 15:07:04 unet mpd: [pptp27] LCP: authorization successful
Oct 21 15:07:09 unet mpd: [pptp27] IPCP: SendConfigReq #4
Oct 21 15:07:09 unet mpd: IPADDR 172.16.0.1
Oct 21 15:07:09 unet mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Oct 21 15:07:09 unet mpd: [pptp27] CCP: SendConfigReq #4
Oct 21 15:07:09 unet mpd: [pptp27] CCP: Checking whether 40 bits are
enabled -> yes (RADIUS)
Oct 21 15:07:09 unet mpd: [pptp27] CCP: Checking whether 56 bits are
enabled -> no (RADIUS)
Oct 21 15:07:09 unet mpd: [pptp27] CCP: Checking whether 128 bits are
enabled -> yes (RADIUS)
Oct 21 15:07:09 unet mpd: MPPC
Oct 21 15:07:09 unet mpd: 0x00000060: MPPE, 40 bit, 128 bit
Oct 21 15:07:09 unet mpd: [pptp27] CCP: rec'd Configure Request #5 link
0 (Req-Sent)
Oct 21 15:07:09 unet mpd: MPPC
Oct 21 15:07:09 unet mpd: 0x010000e0: MPPE, 40 bit, 56 bit, 128 bit,
stateless
Oct 21 15:07:09 unet mpd: [pptp27] CCP: Checking whether 40 bits are
acceptable -> yes (RADIUS)
Oct 21 15:07:09 unet mpd: [pptp27] CCP: Checking whether 56 bits are
acceptable -> no (RADIUS)
Oct 21 15:07:09 unet mpd: [pptp27] CCP: Checking whether 128 bits are
acceptable -> yes (RADIUS)
Oct 21 15:07:09 unet mpd: [pptp27] CCP: SendConfigNak #5
Oct 21 15:07:09 unet mpd: MPPC
Oct 21 15:07:09 unet mpd: 0x00000040: MPPE, 128 bit
Oct 21 15:07:13 unet mpd: [pptp27] IPCP: SendConfigReq #5
Oct 21 15:07:13 unet mpd: IPADDR 172.16.0.1
Oct 21 15:07:13 unet mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Oct 21 15:07:13 unet mpd: [pptp27] CCP: SendConfigReq #5
Oct 21 15:07:13 unet mpd: [pptp27] CCP: Checking whether 40 bits are
enabled -> yes (RADIUS)
Oct 21 15:07:13 unet mpd: [pptp27] CCP: Checking whether 56 bits are
enabled -> no (RADIUS)
Oct 21 15:07:13 unet mpd: [pptp27] CCP: Checking whether 128 bits are
enabled -> yes (RADIUS)
Oct 21 15:07:13 unet mpd: MPPC
Oct 21 15:07:13 unet mpd: 0x00000060: MPPE, 40 bit, 128 bit
Oct 21 15:07:13 unet mpd: [pptp27] IPCP: rec'd Configure Request #6 link
0 (Req-Sent)
Oct 21 15:07:13 unet mpd: IPADDR 0.0.0.0
Oct 21 15:07:37 unet mpd: MPPC
Oct 21 15:07:37 unet mpd: 0x00000040: MPPE, 128 bit
Oct 21 15:07:37 unet mpd: [pptp27] IPCP: state change Req-Sent --> Stopped
Oct 21 15:07:37 unet mpd: [pptp27] IPCP: LayerFinish
Oct 21 15:07:37 unet mpd: [pptp27] IPCP: parameter negotiation failed
Oct 21 15:07:37 unet mpd: [pptp27] IPCP: LayerFinish
Oct 21 15:07:37 unet mpd: [pptp27] CCP: state change Req-Sent --> Stopped
Oct 21 15:07:37 unet mpd: [pptp27] CCP: LayerFinish
Oct 21 15:07:37 unet mpd: [pptp27] CCP: parameter negotiation failed
Oct 21 15:07:37 unet mpd: [pptp27] CCP: Close event
Oct 21 15:07:37 unet mpd: [pptp27] CCP: state change Stopped --> Closed
Oct 21 15:07:37 unet mpd: [pptp27] CCP: LayerFinish
Oct 21 15:07:39 unet mpd: [pptp27] IPCP: rec'd Configure Request #14
link 0 (Stopped)
Oct 21 15:07:39 unet mpd: IPADDR 0.0.0.0
Oct 21 15:07:39 unet mpd: NAKing with 172.16.50.81
Oct 21 15:07:39 unet mpd: PRIDNS 0.0.0.0
Oct 21 15:07:39 unet mpd: NAKing with 10.50.9.1
Oct 21 15:07:39 unet mpd: PRINBNS 0.0.0.0
Oct 21 15:07:39 unet mpd: NAKing with 10.50.9.1
Oct 21 15:07:39 unet mpd: SECDNS 0.0.0.0
Oct 21 15:07:39 unet mpd: SECNBNS 0.0.0.0
Oct 21 15:07:39 unet mpd: [pptp27] IPCP: LayerStart
Oct 21 15:07:39 unet mpd: [pptp27] IPCP: SendConfigReq #11
Oct 21 15:07:39 unet mpd: IPADDR 172.16.0.1
Oct 21 15:07:39 unet mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Oct 21 15:07:39 unet mpd: [pptp27] IPCP: SendConfigRej #14
Oct 21 15:07:39 unet mpd: SECDNS 0.0.0.0
Oct 21 15:07:39 unet mpd: SECNBNS 0.0.0.0
Oct 21 15:07:39 unet mpd: [pptp27] IPCP: state change Stopped --> Req-Sent
Oct 21 15:07:39 unet mpd: [pptp27] bundle: CLOSE event in state OPENED
Oct 21 15:07:39 unet mpd: [pptp27] closing link "pptp27"...
Oct 21 15:07:39 unet mpd: [pptp27] IPCP: SendConfigReq #12
Oct 21 15:07:39 unet mpd: IPADDR 172.16.0.1
Oct 21 15:07:39 unet mpd: COMPPROTO VJCOMP, 16 comp. channels, no comp-cid
Oct 21 15:07:39 unet mpd: pptp36-0: ignoring SetLinkInfo
Oct 21 15:07:42 unet mpd: [pptp27] IPCP: rec'd Configure Reject #6 link
0 (Req-Sent)
Oct 21 15:07:42 unet mpd: Wrong id#, expecting 12
Oct 21 15:07:42 unet mpd: [pptp27] bundle: CLOSE event in state CLOSED
Oct 21 15:07:42 unet mpd: [pptp27] closing link "pptp27"...
Oct 21 15:07:42 unet mpd: [pptp27] link: CLOSE event
Oct 21 15:07:42 unet mpd: [pptp27] LCP: Close event
Oct 21 15:07:42 unet mpd: [pptp27] LCP: state change Opened --> Closing
Oct 21 15:07:42 unet mpd: [pptp27] LCP: phase shift NETWORK --> TERMINATE
Oct 21 15:07:42 unet mpd: [pptp27] LCP: state change Opened --> Closing
Oct 21 15:07:42 unet mpd: [pptp27] LCP: phase shift NETWORK --> TERMINATE
Oct 21 15:07:42 unet mpd: [pptp27] RADIUS: RadiusAccount for: nAggOHOK
Oct 21 15:07:42 unet mpd: [pptp27] RADIUS: using /etc/radius.conf
Oct 21 15:07:42 unet mpd: [pptp27] RADIUS: RadiusAddServer Adding 127.0.0.1
Oct 21 15:07:42 unet mpd: [pptp27] RADIUS: Termination cause: Protocol
error:PPP layer IPCP failed: parameter negotiation fai
led, RADIUS: 15
Oct 21 15:07:42 unet mpd: [pptp27] RADIUS: RadiusAccount: Sending
accounting data (Type: 2)
Oct 21 15:07:42 unet mpd: [pptp27] RADIUS: RadiusSendRequest:
RAD_ACCOUNTING_RESPONSE for user nAggOHOK
Oct 21 15:07:42 unet mpd: [pptp27] setting interface ng27 MTU to 1400 bytes
Oct 21 15:07:42 unet mpd: [pptp27] up: 0 links, total bandwidth 9600 bps
Oct 21 15:07:42 unet mpd: [pptp27] RADIUS: Down Event
Oct 21 15:07:42 unet mpd: [pptp27] IPCP: Down event
Oct 21 15:07:42 unet mpd: [pptp27] IPCP: state change Req-Sent --> Starting
Oct 21 15:07:42 unet mpd: [pptp27] CCP: Down event
Oct 21 15:07:42 unet mpd: [pptp27] CCP: state change Closed --> Initial
Oct 21 15:07:42 unet mpd: [pptp27] CCP: Close event
Oct 21 15:07:42 unet mpd: [pptp27] closing link "pptp27"...
Oct 21 15:07:42 unet mpd: [pptp27] LCP: SendTerminateReq #3
Oct 21 15:07:42 unet mpd: [pptp27] LCP: LayerDown
Oct 21 15:07:44 unet mpd: [pptp27] rec'd proto CCP during terminate phase
Oct 21 15:07:44 unet mpd: [pptp27] bundle: OPEN event in state CLOSED
Oct 21 15:07:44 unet mpd: [pptp27] opening link "pptp27"...
Oct 21 15:07:44 unet mpd: [pptp27] link: CLOSE event
Oct 21 15:07:44 unet mpd: [pptp27] LCP: Close event
Oct 21 15:07:44 unet mpd: [pptp27] LCP: SendTerminateReq #4
Oct 21 15:07:47 unet mpd: [pptp27] rec'd proto IPCP during terminate phase
Oct 21 15:07:47 unet mpd: [pptp27] link: CLOSE event
Oct 21 15:07:47 unet mpd: [pptp27] LCP: Close event
Oct 21 15:07:47 unet mpd: [pptp27] LCP: state change Closing --> Closed
Oct 21 15:07:47 unet mpd: [pptp27] LCP: phase shift TERMINATE --> ESTABLISH
Oct 21 15:07:47 unet mpd: [pptp27] LCP: LayerFinish
Oct 21 15:07:47 unet mpd: [pptp27] PPTP call terminated
Oct 21 15:07:47 unet mpd: [pptp27] IFACE: Close event
Oct 21 15:07:47 unet mpd: [pptp27] IPCP: Close event
Oct 21 15:07:47 unet mpd: [pptp27] IPCP: state change Starting --> Initial
Oct 21 15:07:47 unet mpd: [pptp27] IPCP: LayerFinish
Oct 21 15:07:47 unet mpd: [pptp27] IFACE: Close event
Oct 21 15:07:47 unet mpd: pptp23: closing connection with 10.50.3.52:2142
Oct 21 15:07:50 unet mpd: [pptp27] rec'd proto CCP during establishment
phase
Oct 21 15:07:50 unet mpd: [pptp27] bundle: CLOSE event in state OPENED
Oct 21 15:07:50 unet mpd: [pptp27] link: OPEN event
Oct 21 15:07:50 unet mpd: [pptp27] LCP: Open event
Oct 21 15:07:50 unet mpd: [pptp27] link: OPEN event
Oct 21 15:07:50 unet mpd: [pptp27] LCP: Open event
Oct 21 15:07:50 unet mpd: [pptp27] LCP: state change Closed --> Req-Sent
Oct 21 15:07:50 unet mpd: [pptp27] LCP: LayerStart
Oct 21 15:07:50 unet mpd: [pptp27] LCP: SendConfigReq #5
Oct 21 15:07:50 unet mpd: ACFCOMP
Oct 21 15:07:50 unet mpd: PROTOCOMP
Oct 21 15:07:50 unet mpd: MRU 1400
Oct 21 15:07:50 unet mpd: MAGICNUM bbc0f6b6
Oct 21 15:07:50 unet mpd: AUTHPROTO CHAP MSOFTv2
Oct 21 15:07:50 unet mpd: MP MRRU 1600
Oct 21 15:07:50 unet mpd: MP SHORTSEQ
Oct 21 15:07:50 unet mpd: ENDPOINTDISC [802.1] 00 60 98 ef 0d 86
Oct 21 15:07:50 unet mpd: [pptp27] error writing len 42 frame to bypass:
Network is down
Oct 21 15:07:50 unet mpd: [pptp27] device: CLOSE event in state UP
Oct 21 15:07:50 unet mpd: [pptp27] IFACE: Close event
Oct 21 15:07:50 unet mpd: [pptp27] device is now in state CLOSING
Oct 21 15:07:50 unet mpd: [pptp27] rec'd proto IPCP during establishment
phase
Oct 21 15:07:50 unet mpd: [pptp27] device: DOWN event in state CLOSING
Oct 21 15:07:50 unet mpd: [pptp27] device is now in state DOWN
Oct 21 15:07:50 unet mpd: [pptp27] rec'd proto CCP during establishment
phase
Oct 21 15:07:50 unet mpd: [pptp27] link: DOWN event
Oct 21 15:07:50 unet mpd: [pptp27] LCP: Down event
Oct 21 15:07:50 unet mpd: [pptp27] LCP: state change Req-Sent --> Starting
Oct 21 15:07:50 unet mpd: [pptp27] LCP: phase shift ESTABLISH --> DEAD
Oct 21 15:07:50 unet mpd: [pptp27] device: OPEN event in state DOWN
Oct 21 15:07:50 unet mpd: [pptp27] pausing 9 seconds before open
Oct 21 15:07:50 unet mpd: [pptp27] device is now in state DOWN
Oct 21 15:07:50 unet mpd: [pptp27] rec'd proto IPCP while dead
Oct 21 15:07:50 unet mpd: [pptp27] device: DOWN event in state DOWN
Oct 21 15:07:50 unet mpd: [pptp27] device is now in state DOWN
Oct 21 15:07:50 unet mpd: [pptp27] rec'd proto CCP while dead
Oct 21 15:07:50 unet mpd: [pptp27] link: DOWN event
Oct 21 15:07:50 unet mpd: [pptp27] LCP: Down event
Oct 21 15:07:50 unet mpd: [pptp27] device: OPEN event in state DOWN
Oct 21 15:07:50 unet mpd: [pptp27] device is now in state DOWN
Oct 21 15:07:50 unet mpd: [pptp27] rec'd proto CCP while dead
Oct 21 15:07:50 unet mpd: [pptp27] device: OPEN event in state DOWN
Oct 21 15:07:50 unet mpd: [pptp27] device is now in state DOWN
Oct 21 15:07:51 unet mpd: [pptp27] rec'd proto IPCP while dead
Oct 21 15:07:51 unet mpd: [pptp27] device: OPEN event in state DOWN
Oct 21 15:07:51 unet mpd: [pptp27] device is now in state DOWN
Oct 21 15:07:51 unet mpd: mpd: PPTP connection from 10.50.3.52:2149
В radius log
Fri Oct 21 15:06:57 2005 : Auth: rlm_nibs (rlm_nibs_postauth): User
`nAggOHOK' login OK [127.0.0.1:27] |10.50.3.52|
Fri Oct 21 15:07:00 2005 : Info: rlm_nibs (rlm_nibs_checksimul): User
`nAggOHOK' zapped by port eq
Fri Oct 21 15:07:00 2005 : Auth: rlm_nibs (rlm_nibs_accounting): User
`nAggOHOK' logout [127.0.0.1:27]
Fri Oct 21 15:07:00 2005 : Auth: rlm_nibs (rlm_nibs_postauth): User
`nAggOHOK' login OK [127.0.0.1:27] |10.50.3.52|
Fri Oct 21 15:07:04 2005 : Auth: rlm_nibs (rlm_nibs_postauth): User
`nAggOHOK' login OK [127.0.0.1:27] |10.50.3.52|
Fri Oct 21 15:06:57 2005 : Auth: rlm_nibs (rlm_nibs_postauth): User
`nAggOHOK' login OK [127.0.0.1:27] |10.50.3.52|
Fri Oct 21 15:07:00 2005 : Info: rlm_nibs (rlm_nibs_checksimul): User
`nAggOHOK' zapped by port eq
Fri Oct 21 15:07:00 2005 : Error: rlm_nibs (nibs_add_current): Stop
packet with zero session length for user `nAggOHOK' [127.
0.0.1:27]
Fri Oct 21 15:07:00 2005 : Auth: rlm_nibs (rlm_nibs_accounting): User
`nAggOHOK' logout [127.0.0.1:27]
Fri Oct 21 15:07:00 2005 : Auth: rlm_nibs (rlm_nibs_postauth): User
`nAggOHOK' login OK [127.0.0.1:27] |10.50.3.52|
Fri Oct 21 15:07:04 2005 : Auth: rlm_nibs (rlm_nibs_postauth): User
`nAggOHOK' login OK [127.0.0.1:27] |10.50.3.52|
--- ifmail v.2.15dev5.3
* Origin: RTComm.RU (2:5020/400)
Вернуться к списку тем, сортированных по: возрастание даты уменьшение даты тема автор
|
