>[оверквотинг удален]
>> *Nov 30 08:12:03.600: Vi2 PPP: Phase is DOWN
>> *Nov 30 08:12:03.604: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di1
>> Nov 30 08:12:03.608: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down
>> По debug ppp error
>> вообще ничего не выдает. Такое ощущение что Cisco сама кладет соединение. Один
>> раз в show pppoe sesson увидел UP состояние на секунду.
> в конфе Cisco строка "no aaa new-model"
> это ни как не отражается на сессии, отношение ведь только к авторизации
> пользователей на самой Cisco?
> В логах дебага увидел строчку NET STOP send to AAA увеличил буфер, теперь все логи есть:
*Nov 30 08:29:43.768: %DIALER-6-BIND: Interface Vi2 bound to profile Di1
*Nov 30 08:29:43.776: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to up
*Nov 30 08:29:43.776: Vi2 PPP: Sending cstate UP notification
*Nov 30 08:29:43.776: Vi2 PPP: Processing CstateUp message
*Nov 30 08:29:43.776: PPP: Alloc Context [4980E548]
*Nov 30 08:29:43.776: ppp67 PPP: Phase is ESTABLISHING
*Nov 30 08:29:43.776: Vi2 PPP: Using dialer call direction
*Nov 30 08:29:43.776: Vi2 PPP: Treating connection as a callout
*Nov 30 08:29:43.776: Vi2 PPP: Session handle[81000043] Session id[67]
*Nov 30 08:29:43.776: Vi2 LCP: Event[OPEN] State[Initial to Starting]
*Nov 30 08:29:43.776: Vi2 LCP: O CONFREQ [Starting] id 1 len 15
*Nov 30 08:29:43.776: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.776: Vi2 LCP: MagicNumber 0x30949445 (0x050630949445)
*Nov 30 08:29:43.780: Vi2 LCP: Event[UP] State[Starting to REQsent]
*Nov 30 08:29:43.780: Vi2 LCP: I CONFREQ [REQsent] id 189 len 18
*Nov 30 08:29:43.780: Vi2 LCP: MRU 1492 (0x010405D4)
*Nov 30 08:29:43.780: Vi2 LCP: AuthProto PAP (0x0304C023)
*Nov 30 08:29:43.780: Vi2 LCP: MagicNumber 0x36D85284 (0x050636D85284)
*Nov 30 08:29:43.780: Vi2 LCP: O CONFNAK [REQsent] id 189 len 13
*Nov 30 08:29:43.780: Vi2 LCP: MRU 1500 (0x010405DC)
*Nov 30 08:29:43.780: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.780: Vi2 LCP: Event[Receive ConfReq-] State[REQsent to REQsent]
*Nov 30 08:29:43.780: Vi2 LCP: I CONFREJ [REQsent] id 1 len 9
*Nov 30 08:29:43.780: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.780: Vi2 LCP: O CONFREQ [REQsent] id 2 len 15
*Nov 30 08:29:43.780: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.780: Vi2 LCP: MagicNumber 0x30949445 (0x050630949445)
*Nov 30 08:29:43.780: Vi2 LCP: Event[Receive ConfNak/Rej] State[REQsent to REQsent]
*Nov 30 08:29:43.780: Vi2 LCP: I CONFREQ [REQsent] id 190 len 19
*Nov 30 08:29:43.780: Vi2 LCP: MRU 1492 (0x010405D4)
*Nov 30 08:29:43.780: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.784: Vi2 LCP: MagicNumber 0x36D85284 (0x050636D85284)
*Nov 30 08:29:43.784: Vi2 LCP: O CONFNAK [REQsent] id 190 len 8
*Nov 30 08:29:43.784: Vi2 LCP: MRU 1500 (0x010405DC)
*Nov 30 08:29:43.784: Vi2 LCP: Event[Receive ConfReq-] State[REQsent to REQsent]
*Nov 30 08:29:43.784: Vi2 LCP: I CONFREJ [REQsent] id 2 len 9
*Nov 30 08:29:43.784: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.784: Vi2 LCP: O CONFREQ [REQsent] id 3 len 15
*Nov 30 08:29:43.784: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.784: Vi2 LCP: MagicNumber 0x30949445 (0x050630949445)
*Nov 30 08:29:43.784: Vi2 LCP: Event[Receive ConfNak/Rej] State[REQsent to REQsent]
*Nov 30 08:29:43.784: Vi2 LCP: I CONFREQ [REQsent] id 191 len 19
*Nov 30 08:29:43.784: Vi2 LCP: MRU 1492 (0x010405D4)
*Nov 30 08:29:43.784: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.784: Vi2 LCP: MagicNumber 0x36D85284 (0x050636D85284)
*Nov 30 08:29:43.784: Vi2 LCP: O CONFNAK [REQsent] id 191 len 8
*Nov 30 08:29:43.784: Vi2 LCP: MRU 1500 (0x010405DC)
*Nov 30 08:29:43.784: Vi2 LCP: Event[Receive ConfReq-] State[REQsent to REQsent]
*Nov 30 08:29:43.788: Vi2 LCP: I CONFREJ [REQsent] id 3 len 9
*Nov 30 08:29:43.788: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.788: Vi2 LCP: O CONFREQ [REQsent] id 4 len 15
*Nov 30 08:29:43.788: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.788: Vi2 LCP: MagicNumber 0x30949445 (0x050630949445)
*Nov 30 08:29:43.788: Vi2 LCP: Event[Receive ConfNak/Rej] State[REQsent to REQsent]
*Nov 30 08:29:43.788: Vi2 LCP: I CONFREQ [REQsent] id 192 len 19
*Nov 30 08:29:43.788: Vi2 LCP: MRU 1492 (0x010405D4)
*Nov 30 08:29:43.788: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.788: Vi2 LCP: MagicNumber 0x36D85284 (0x050636D85284)
*Nov 30 08:29:43.788: Vi2 LCP: O CONFNAK [REQsent] id 192 len 8
*Nov 30 08:29:43.788: Vi2 LCP: MRU 1500 (0x010405DC)
*Nov 30 08:29:43.788: Vi2 LCP: Event[Receive ConfReq-] State[REQsent to REQsent]
*Nov 30 08:29:43.788: Vi2 LCP: I CONFREJ [REQsent] id 4 len 9
*Nov 30 08:29:43.788: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.788: Vi2 LCP: O CONFREQ [REQsent] id 5 len 15
*Nov 30 08:29:43.788: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.788: Vi2 LCP: MagicNumber 0x30949445 (0x050630949445)
*Nov 30 08:29:43.788: Vi2 LCP: Event[Receive ConfNak/Rej] State[REQsent to REQsent]
*Nov 30 08:29:43.788: Vi2 LCP: I CONFREQ [REQsent] id 193 len 19
*Nov 30 08:29:43.788: Vi2 LCP: MRU 1492 (0x010405D4)
*Nov 30 08:29:43.788: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.788: Vi2 LCP: MagicNumber 0x36D85284 (0x050636D85284)
*Nov 30 08:29:43.788: Vi2 LCP: O CONFNAK [REQsent] id 193 len 8
*Nov 30 08:29:43.788: Vi2 LCP: MRU 1500 (0x010405DC)
*Nov 30 08:29:43.788: Vi2 LCP: Event[Receive ConfReq-] State[REQsent to REQsent]
*Nov 30 08:29:43.792: Vi2 LCP: I CONFREJ [REQsent] id 5 len 9
*Nov 30 08:29:43.792: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.792: Vi2 LCP: O CONFREQ [REQsent] id 6 len 15
*Nov 30 08:29:43.792: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.792: Vi2 LCP: MagicNumber 0x30949445 (0x050630949445)
*Nov 30 08:29:43.792: Vi2 LCP: Event[Receive ConfNak/Rej] State[REQsent to REQsent]
*Nov 30 08:29:43.792: Vi2 LCP: I CONFREQ [REQsent] id 194 len 19
*Nov 30 08:29:43.792: Vi2 LCP: MRU 1492 (0x010405D4)
*Nov 30 08:29:43.792: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.792: Vi2 LCP: MagicNumber 0x36D85284 (0x050636D85284)
*Nov 30 08:29:43.792: Vi2 LCP: Sent too many CONFNAKs. Switch to CONFREJ
*Nov 30 08:29:43.792: Vi2 LCP: O CONFREJ [REQsent] id 194 len 8
*Nov 30 08:29:43.792: Vi2 LCP: MRU 1492 (0x010405D4)
*Nov 30 08:29:43.792: Vi2 LCP: Event[Receive ConfReq-] State[REQsent to REQsent]
*Nov 30 08:29:43.792: Vi2 LCP: I CONFREJ [REQsent] id 6 len 9
*Nov 30 08:29:43.792: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.792: Vi2 LCP: O CONFREQ [REQsent] id 7 len 15
*Nov 30 08:29:43.792: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.792: Vi2 LCP: MagicNumber 0x30949445 (0x050630949445)
*Nov 30 08:29:43.792: Vi2 LCP: Event[Receive ConfNak/Rej] State[REQsent to REQsent]
*Nov 30 08:29:43.792: Vi2 LCP: I CONFREQ [REQsent] id 195 len 15
*Nov 30 08:29:43.792: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.792: Vi2 LCP: MagicNumber 0x36D85284 (0x050636D85284)
*Nov 30 08:29:43.792: Vi2 LCP: O CONFACK [REQsent] id 195 len 15
*Nov 30 08:29:43.792: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.792: Vi2 LCP: MagicNumber 0x36D85284 (0x050636D85284)
*Nov 30 08:29:43.792: Vi2 LCP: Event[Receive ConfReq+] State[REQsent to ACKsent]
*Nov 30 08:29:43.792: Vi2 LCP: I CONFREJ [ACKsent] id 7 len 9
*Nov 30 08:29:43.792: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.796: Vi2 LCP: O CONFREQ [ACKsent] id 8 len 15
*Nov 30 08:29:43.796: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.796: Vi2 LCP: MagicNumber 0x30949445 (0x050630949445)
*Nov 30 08:29:43.796: Vi2 LCP: Event[Receive ConfNak/Rej] State[ACKsent to ACKsent]
*Nov 30 08:29:43.796: Vi2 LCP: I CONFREJ [ACKsent] id 8 len 9
*Nov 30 08:29:43.796: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.796: Vi2 LCP: O CONFREQ [ACKsent] id 9 len 15
*Nov 30 08:29:43.796: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.796: Vi2 LCP: MagicNumber 0x30949445 (0x050630949445)
*Nov 30 08:29:43.796: Vi2 LCP: Event[Receive ConfNak/Rej] State[ACKsent to ACKsent]
*Nov 30 08:29:43.796: Vi2 LCP: I CONFREJ [ACKsent] id 9 len 9
*Nov 30 08:29:43.796: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.796: Vi2 LCP: O CONFREQ [ACKsent] id 10 len 15
*Nov 30 08:29:43.796: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.796: Vi2 LCP: MagicNumber 0x30949445 (0x050630949445)
*Nov 30 08:29:43.796: Vi2 LCP: Event[Receive ConfNak/Rej] State[ACKsent to ACKsent]
*Nov 30 08:29:43.800: Vi2 LCP: I CONFREJ [ACKsent] id 10 len 9
*Nov 30 08:29:43.800: Vi2 LCP: AuthProto CHAP (0x0305C22305)
*Nov 30 08:29:43.800: Vi2 LCP: Received too many CONFREJs. Closing CP
*Nov 30 08:29:43.800: Vi2 PPP DISC: LCP failed to negotiate
*Nov 30 08:29:43.800: PPP: NET STOP send to AAA.
*Nov 30 08:29:43.800: Vi2 LCP: O TERMREQ [ACKsent] id 11 len 4
*Nov 30 08:29:43.800: Vi2 LCP: Event[CLOSE] State[ACKsent to Closing]
*Nov 30 08:29:43.800: Vi2 LCP: I TERMACK [Closing] id 11 len 4
*Nov 30 08:29:43.800: Vi2 LCP: Event[Receive TermAck] State[Closing to Closed]
*Nov 30 08:29:43.800: Vi2 LCP: Event[DOWN] State[Closed to Initial]
*Nov 30 08:29:43.804: Vi2 PPP: Phase is DOWN
*Nov 30 08:29:43.804: %DIALER-6-UNBIND: Interface Vi2 unbound from profile Di1
*Nov 30 08:29:43.808: %LINK-3-UPDOWN: Interface Virtual-Access2, changed state to down