Freebsd 8 (cvs_21.01.2010 12:00) + mpd5.4(CVS) FSM Repeated timeout

[gorec2005 gorec2005]

Log from mpd with enabled debug:

Jan 21 13:30:01 vpn9 mpd: [PoE10-5] LCP: rec'd Configure Request #0
(Req-Sent)
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] MRU 1480
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] MAGICNUM 6b911a4b
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] CALLBACK 6
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] LCP: SendConfigRej #0
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] CALLBACK 6
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] xmit frame to link proto=0xc021
Jan 21 13:30:01 vpn9 mpd: ff 03 c0 21 04 00 00 07 0d 03 06 ...!.......
Jan 21 13:30:01 vpn9 mpd: EVENT: Processing event EVENT_READ
LinkNgDataEvent() done
Jan 21 13:30:01 vpn9 mpd: EVENT: Processing event EVENT_READ
LinkNgDataEvent()
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] rec'd 16 bytes frame from link
proto=0xc021
Jan 21 13:30:01 vpn9 mpd: c0 21 01 01 00 0e 01 04 05 c8 05 06 6b 91 1a 4b
.!..........k..K
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] LCP: rec'd Configure Request #1
(Req-Sent)
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] MRU 1480
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] MAGICNUM 6b911a4b
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] LCP: SendConfigAck #1
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] MRU 1480
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] MAGICNUM 6b911a4b
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] xmit frame to link proto=0xc021
Jan 21 13:30:01 vpn9 mpd: ff 03 c0 21 02 01 00 0e 01 04 05 c8 05 06 6b 91
...!..........k.
Jan 21 13:30:01 vpn9 mpd: 1a 4b .K
Jan 21 13:30:01 vpn9 mpd: [PoE10-5] LCP: state change Req-Sent --> Ack-Sent
Jan 21 13:30:01 vpn9 mpd: EVENT: Processing event EVENT_READ
LinkNgDataEvent() done
Jan 21 13:30:04 vpn9 mpd: EVENT: Processing event EVENT_TIMEOUT
TimerExpires()
Jan 21 13:30:04 vpn9 mpd: EVENT: Processing timer "LCP" FsmTimeout()
Jan 21 13:30:04 vpn9 mpd: [PoE10-5] LCP: SendConfigReq #2
Jan 21 13:30:04 vpn9 mpd: [PoE10-5] PROTOCOMP
Jan 21 13:30:04 vpn9 mpd: [PoE10-5] MRU 1492
Jan 21 13:30:04 vpn9 mpd: [PoE10-5] MAGICNUM 5074160b
Jan 21 13:30:04 vpn9 mpd: [PoE10-5] AUTHPROTO CHAP MSOFTv2
Jan 21 13:30:04 vpn9 mpd: [PoE10-5] xmit frame to link proto=0xc021
Jan 21 13:30:04 vpn9 mpd: ff 03 c0 21 01 02 00 15 07 02 01 04 05 d4 05 06
...!............
Jan 21 13:30:04 vpn9 mpd: 50 74 16 0b 03 05 c2 23 81 Pt.....#.
Jan 21 13:30:04 vpn9 mpd: EVENT: Starting timer "LCP" FsmTimeout() for 2000
ms at fsm.c:426
Jan 21 13:30:04 vpn9 mpd: EVENT: Registering event EVENT_TIMEOUT
TimerExpires() at timer.c:50
Jan 21 13:30:04 vpn9 mpd: EVENT: Registering event EVENT_TIMEOUT
TimerExpires() done at timer.c:50
Jan 21 13:30:04 vpn9 mpd: EVENT: Processing timer "LCP" FsmTimeout() done
Jan 21 13:30:04 vpn9 mpd: EVENT: Processing event EVENT_TIMEOUT
TimerExpires() done
Jan 21 13:30:04 vpn9 mpd: EVENT: Processing event EVENT_READ
LinkNgDataEvent()
Jan 21 13:30:04 vpn9 mpd: [PoE10-5] rec'd 8 bytes frame from link
proto=0xc021


At position between
Jan 21 13:30:01 vpn9 mpd: EVENT: Processing event EVENT_READ
LinkNgDataEvent() done
and
Jan 21 13:30:04 vpn9 mpd: EVENT: Processing event EVENT_TIMEOUT
TimerExpires()

process stops - for a client it looks like the usual delay, but the
appearance of such a clear and pleasant ... - Recommend that this can do?