From: magmax (magmax@bigpond.net.au)
Date: Fri Feb 10 2006 - 22:56:54 GMT-3
Guys,
Can anyone tell me why this BGP peer is failing?
Topology
R5 ---R3 -------R4
p.s
I don't see any issues when peering with R4 from R3 (exact same config)
router bgp 300
no synchronization
bgp router-id 150.1.3.3
bgp log-neighbor-changes
neighbor 154.1.0.5 remote-as 400
neighbor 154.1.0.5 send-community
no auto-summary
Rack1R3#ping 154.1.0.5 repeat 10
Type escape sequence to abort.
Sending 10, 100-byte ICMP Echos to 154.1.0.5, timeout is 2 seconds:
!!!!!!!!!!
Success rate is 100 percent (10/10), round-trip min/avg/max = 56/59/60 ms
Rack1R3#
Rack1R5#sh run | begin router bgp
router bgp 400
no synchronization
bgp router-id 150.1.5.5
bgp log-neighbor-changes
neighbor 154.1.0.3 remote-as 300
neighbor 154.1.0.3 send-community
no auto-summary
Debug output on R3!
*Mar 4 16:42:03.695: TCP0: FIN processed
*Mar 4 16:42:03.695: TCP0: state was FINWAIT1 -> CLOSING [179 ->
154.1.0.5(5294
5)]
*Mar 4 16:42:04.267: BGP: 154.1.0.5 went from Idle to Active
*Mar 4 16:42:04.267: BGP: 154.1.0.5 open active delayed 31629ms (35000ms
max, 2
8% jitter)
Rack1R3#
*Mar 4 16:42:20.259: TCP: sending RST, seq 0, ack 4070132017
*Mar 4 16:42:20.259: TCP: sent RST to 154.1.0.4:64767 from 154.1.0.3:179
Rack1R3#
*Mar 4 16:42:32.575: TCB65594E34 created
*Mar 4 16:42:32.575: TCP0: state was LISTEN -> SYNRCVD [179 ->
154.1.0.5(58110)]
*Mar 4 16:42:32.575: TCP: tcb 65594E34 connection to 154.1.0.5:58110, peer
MSS 1460, MSS is 516
*Mar 4 16:42:32.579: TCP: sending SYN, seq 533987964, ack 2126158377
*Mar 4 16:42:32.579: TCP0: Connection to 154.1.0.5:58110, advertising MSS
1460
*Mar 4 16:42:32.611: TCP0: state was SYNRCVD -> ESTAB [179 ->
154.1.0.5(58110)]
*Mar 4 16:42:32.611: TCB653A5B70 callback, connection queue = 1
*Mar 4 16:42:32.611: TCB653A5B70 accepting 65594E34 from 154.1.0.5.58110
*Mar 4 16:42:32.615: BGP: 154.1.0.5 passive open to 154.1.0.3
*Mar 4 16:42:32.615: BGP: 154.1.0.5 went from Active to Idle
*Mar 4 16:42:32.615: BGP: 154.1.0.5 went from Idle to Connect
*Mar 4 16:42:32.627: BGP: 154.1.0.5 rcv message type 1, length (excl.
header) 26
*Mar 4 16:42:32.627: BGP: 154.1.0.5 rcv OPEN, version 4, holdtime 180
seconds
*Mar 4 16:42:32.627: BGP: 154.1.0.5 went fr
Rack1R3#om Connect to OpenSent
*Mar 4 16:42:32.631: BGP: 154.1.0.5 sending OPEN, version 4, my as: 300,
holdtime 180 seconds
*Mar 4 16:42:32.631: BGP: 154.1.0.5 rcv OPEN w/ OPTION parameter len: 16
*Mar 4 16:42:32.631: BGP: 154.1.0.5 rcvd OPEN w/ optional parameter type 2
(Capability) len 6
*Mar 4 16:42:32.631: BGP: 154.1.0.5 OPEN has CAPABILITY code: 1, length 4
*Mar 4 16:42:32.631: BGP: 154.1.0.5 OPEN has MP_EXT CAP for afi/safi: 1/1
*Mar 4 16:42:32.631: BGP: 154.1.0.5 rcvd OPEN w/ optional parameter type 2
(Capability) len 2
*Mar 4 16:42:32.631: BGP: 154.1.0.5 OPEN has CAPABILITY code: 128, length 0
*Mar 4 16:42:32.631: BGP: 154.1.0.5 OPEN has ROUTE-REFRESH capability(old)
for all address-families
*Mar 4 16:42:32.631: BGP: 154.1.0.5 rcvd OPEN w/ optional parameter type 2
(Capability) len 2
*Mar 4 16:42:32.631: BGP: 154.1.0.5 OPEN has CAPABILITY code: 2, length 0
*Mar 4 16:42:32.631: BGP: 154.1.0.5 OPEN has ROUTE-REFRESH capability(new)
for all address-families
BGP: 154.1.0.5 rcvd OPEN w/ remote AS 400
*Mar 4 16:42:32.635: BGP: 154.1.0.5 went from OpenSent to OpenConfirm
*Mar 4 16:42:32.635: BGP: 154.1.0.5 send message type 1, length (incl.
header) 45
Rack1R3#
Rack1R3#
*Mar 4 16:42:34.463: TCP0: bad seg from 154.1.0.5 -- outside window: port
179 seq 2126158377 ack 533987965 rcvnxt 2126158422 rcvwnd 16339 len 45
*Mar 4 16:42:34.475: 154.1.0.3:179 <---> 154.1.0.5:58110 congestion
window changes
*Mar 4 16:42:34.475: cwnd from 1461 to 1460, ssthresh from 65535 to 2920
*Mar 4 16:42:34.475: TCP0: timeout #1 - timeout is 3674 ms, seq 533987965
*Mar 4 16:42:34.475: TCP: (179) -> 154.1.0.5(58110)
Rack1R3#
*Mar 4 16:42:38.139: TCP0: bad seg from 154.1.0.5 -- outside window: port
179 seq 2126158377 ack 533987965 rcvnxt 2126158422 rcvwnd 16339 len 45
*Mar 4 16:42:38.151: TCP0: timeout #2 - timeout is 7348 ms, seq 533987965
*Mar 4 16:42:38.151: TCP: (179) -> 154.1.0.5(58110)
Rack1R3#
*Mar 4 16:42:45.487: TCP0: bad seg from 154.1.0.5 -- outside window: port
179 seq 2126158377 ack 533987965 rcvnxt 2126158422 rcvwnd 16339 len 45
*Mar 4 16:42:45.499: TCP0: timeout #3 - timeout is 14696 ms, seq 533987965
*Mar 4 16:42:45.499: TCP: (179) -> 154.1.0.5(58110)
Rack1R3#
*Mar 4 16:42:47.983: TCP: sending RST, seq 0, ack 1719142085
*Mar 4 16:42:47.983: TCP: sent RST to 154.1.0.4:23273 from 154.1.0.3:179
*Mar 4 16:42:48.643: TCP0: state was CLOSING -> CLOSED [179 ->
154.1.0.5(19509)]
*Mar 4 16:42:48.643: TCB 0x655897D8 destroyed
Rack1R3#
*Mar 4 16:42:50.743: TCP: sending RST, seq 0, ack 653177199
*Mar 4 16:42:50.743: TCP: sent RST to 154.1.38.8:11002 from 154.1.38.3:179
Rack1R3#
*Mar 4 16:42:55.459: TCP0: bad seg from 154.1.0.5 -- outside window: port
179 seq 445911247 ack 1084204555 rcvnxt 445911314 rcvwnd 16318 len 66
*Mar 4 16:42:55.467: TCP0: Data repacketized, seq 1084204555, sent 85 byte
*Mar 4 16:42:55.467: TCP0: timeout #8 - timeout is 58784 ms, seq 1084204555
*Mar 4 16:42:55.467: TCP: (179) -> 154.1.0.5(52945)
Rack1R3#
*Mar 4 16:43:00.183: TCP0: bad seg from 154.1.0.5 -- outside window: port
179 seq 2126158377 ack 533987965 rcvnxt 2126158422 rcvwnd 16339 len 45
*Mar 4 16:43:00.195: TCP0: timeout #4 - timeout is 29392 ms, seq 533987965
*Mar 4 16:43:00.195: TCP: (179) -> 154.1.0.5(58110)
Rack1R3#
*Mar 4 16:43:06.271: TCP: sending RST, seq 0, ack 3448371264
*Mar 4 16:43:06.271: TCP: sent RST to 154.1.23.2:11005 from 154.1.23.3:179
Rack1R3#
*Mar 4 16:43:08.087: TCP: sending RST, seq 0, ack 1002150223
*Mar 4 16:43:08.087: TCP: sent RST to 154.1.13.1:11004 from 154.1.13.3:179
Debug on R5
Rack1R5#CLEAR IP BGp 154.1.0.3
Rack1R5#
*Mar 2 08:48:40.995: BGPNSF state: 154.1.0.3 went from nsf_not_active to
nsf_not_active
*Mar 2 08:48:40.995: BGP: 154.1.0.3 went from Active to Idle
*Mar 2 08:48:40.995: BGP: 154.1.0.3 went from Idle to Active
*Mar 2 08:48:40.999: BGP: 154.1.0.3 open active delayed 34673ms (35000ms
max, 28% jitter)
Rack1R5#
*Mar 2 08:48:55.123: TCP0: timeout #9 - timeout is 58784 ms, seq 3145414371
*Mar 2 08:48:55.123: TCP: (11535) -> 154.1.0.3(179)
Rack1R5#
*Mar 2 08:49:04.739: TCP0: Data repacketized, seq 2328410389, sent 66 byte
*Mar 2 08:49:04.739: TCP0: timeout #8 - timeout is 58784 ms, seq 2328410389
*Mar 2 08:49:04.739: TCP: (61744) -> 154.1.0.3(179)
Rack1R5#
*Mar 2 08:49:15.675: BGP: 154.1.0.3 open active, local address 154.1.0.5
*Mar 2 08:49:15.675: TCB644AE918 created
*Mar 2 08:49:15.675: TCB644AE918 setting property TCP_WINDOW_SIZE (0)
6551FDC4
*Mar 2 08:49:15.675: TCB644AE918 setting property TCP_MD5KEY (5) 0
*Mar 2 08:49:15.675: TCB644AE918 setting property TCP_TOS (11) 6551FDB0
*Mar 2 08:49:15.675: TCP: Random local port generated 19138
*Mar 2 08:49:15.675: TCB644AE918 bound to 154.1.0.5.19138
*Mar 2 08:49:15.679: TCP: sending SYN, seq 738426187, ack 0
*Mar 2 08:49:15.679: TCP0: Connection to 154.1.0.3:179, advertising MSS
1460
*Mar 2 08:49:15.679: TCP0: state was CLOSED -> SYNSENT [19138 ->
154.1.0.3(179)]
Rack1R5#
*Mar 2 08:49:15.711: TCP0: state was SYNSENT -> ESTAB [19138 ->
154.1.0.3(179)]
*Mar 2 08:49:15.711: TCP: tcb 644AE918 connection to 154.1.0.3:179, peer
MSS 1460, MSS is 1460
*Mar 2 08:49:15.711: TCB644AE918 connected to 154.1.0.3.179
*Mar 2 08:49:15.715: BGP: 154.1.0.3 went from Active to OpenSent
*Mar 2 08:49:15.715: BGP: 154.1.0.3 sending OPEN, version 4, my as: 400,
holdtime 180 seconds
*Mar 2 08:49:15.715: BGP: 154.1.0.3 send message type 1, length (incl.
header) 45
Rack1R5#
*Mar 2 08:49:17.559: 154.1.0.5:19138 <---> 154.1.0.3:179 congestion
window changes
*Mar 2 08:49:17.559: cwnd from 1460 to 1460, ssthresh from 65535 to 2920
*Mar 2 08:49:17.559: TCP0: timeout #1 - timeout is 3674 ms, seq 738426188
*Mar 2 08:49:17.559: TCP: (19138) -> 154.1.0.3(179)
Rack1R5#
*Mar 2 08:49:21.235: TCP0: timeout #2 - timeout is 7348 ms, seq 738426188
*Mar 2 08:49:21.235: TCP: (19138) -> 154.1.0.3(179)
Rack1R5#
*Mar 2 08:49:28.583: TCP0: timeout #3 - timeout is 14696 ms, seq 738426188
*Mar 2 08:49:28.583: TCP: (19138) -> 154.1.0.3(179)
Rack1R5#
*Mar 2 08:49:43.279: TCP0: timeout #4 - timeout is 29392 ms, seq 738426188
*Mar 2 08:49:43.279: TCP: (19138) -> 154.1.0.3(179)
Rack1R5#
*Mar 2 08:49:53.907: TCP0: timeout #10 - timeout is 58784 ms, seq
3145414371
*Mar 2 08:49:53.907: TCP: (11535) -> 154.1.0.3(179)
Rack1R5#
*Mar 2 08:50:03.523: TCP0: timeout #9 - timeout is 58784 ms, seq 2328410389
*Mar 2 08:50:03.523: TCP: (61744) -> 154.1.0.3(179)
Rack1R5#
*Mar 2 08:50:12.671: TCP0: timeout #5 - timeout is 58784 ms, seq 738426188
*Mar 2 08:50:12.671: TCP: (19138) -> 154.1.0.3(179)
Rack1R5#sh running-config interface serial 0/0
Building configuration...
Current configuration : 288 bytes
!
interface Serial0/0
ip address 154.1.0.5 255.255.255.0
encapsulation frame-relay
ip ospf network point-to-multipoint non-broadcast
ip ospf priority 0
no dce-terminal-timing-enable
frame-relay map ip 154.1.0.3 503
frame-relay map ip 154.1.0.4 503
no frame-relay inverse-arp
end
Rack1R3#sh running-config interface serial 0/0
Building configuration...
Current configuration : 268 bytes
!
interface Serial0/0
ip address 154.1.0.3 255.255.255.0
encapsulation frame-relay
ip ospf network point-to-multipoint non-broadcast
no dce-terminal-timing-enable
frame-relay map ip 154.1.0.4 304
frame-relay map ip 154.1.0.5 305
no frame-relay inverse-arp
end
Max
This archive was generated by hypermail 2.1.4 : Wed Mar 01 2006 - 11:28:17 GMT-3