Knowledge Search


×
 

[ScreenOS] How to troubleshoot BGP issues

  [KB21496] Show Article Properties


Summary:
This article provides information on how to Troubleshoot BGP issues.
Symptoms:
This article provides information on how to troubleshoot BGP issues, what logs need to be collected, and what to check in these logs.
Cause:

Solution:
Assuming that the BGP configuration on devices acting in neighborship is correct, these are a few troubleshooting tips to bring the peers in Established state:


Check the state of the BGP neighborship by typing the following command:

> get vrouter <virtual router name> protocol bgp 


Example:

SSG140(M)-> get vr trust proto bgp neighbor

Peer AS Remote IP Local IP Wt Status State ConnID Up/Down
--------------------------------------------------------------------------------------
1111 10.204.115.29 0.0.0.0 100 Enabled ACTIVE 0 20:59:03

total 1 BGP peers shown

If the state is shown as Established, then troubleshoot further to identify the possible reasons.

The common reasons for BGP to be stuck in IDLE or ACTIVE state are listed below. Narrow down to the reason as per the condition.

  1. BGP stuck in IDLE state. The possible reasons are:

    • TCP port 179 is not open.

    • Peer address configured incorrectly on either router.

    • AS number configured incorrectly on either router.

    • BGP configuration error.


  2. BGP stuck in ACTIVE state. The possible reasons are:

    • TCP connection is initiated and it is in ACTIVE state i.e. LISTENING state.It is waiting for the TCP connection to complete.

    • Peer address configured incorrectly on either router.

    • AS number configured incorrectly on either router.

    • BGP configuration error.

    • Network congestion.

    • Interface flapping.

Troubleshooting BGP issues

BGP Messages are to be looked into during troubleshooting and it is important to collect the logs as follows:

  • >get vrouter <virtual router name> protocol bgp

  • >get vrouter <virtual router name> protocol bgp neighbor

  • >get vrouter <virtual router name> protocol bgp config

  • >get event - Need to check the messages related to BGP changing states.

  • >get log sys

  • >get socket -  need to know the socket information which helps in understanding the application layer.

  • >get tcp - to know the detailed information about the transport layer

  • >get socket id <socket ID>

  • >get route

Collect the debug logs. The debug logs will show us what is happening between the Application layer and Transport layer.
  • >debug bgp all

  • >debug socket all

  • >debug flow basic

  • >debug tcp all


+What to check in the above mentioned commands to troubleshoot BGP issues:

View BGP configuration in particular VR:

To view the configurable options on particular vr, you can use the following command:

->get vrouter <virtual router name> protocol bgp  SSG140(M)-> get vrouter trust-vr protocol bgp  Admin State: enable Local Router ID: 192.168.1.2 Local AS number: 1111 Hold time: 180 >>Default value Keepalive interval: 60 = 1/3 hold time, default Retry time: 120 Local MED is(ipv4): 0 Local MED is(ipv6): 0 Always compare MED(ipv4): disable Always compare MED(ipv6): disable Local preference(ipv4): 100 Local preference(ipv6): 100 Route Flap Damping(ipv4): disable Route Flap Damping(ipv6): disable IGP synchronization(ipv4): disable IGP synchronization(ipv6): enable                                              >>Enable this option if using IBGP Route reflector: disable
Cluster ID: not set (ID = 0)
Confederation based on RFC 1965
Confederation: disable (confederation ID = 0)
Member AS: none
Origin default route(ipv4): disable >>To advertise the default route to the BGP peer     Origin default route(ipv6): disable
Ignore default route(ipv4): disable >>By enabling this option ,the default route advertised from the BGP peer is rejected Ignore default route(ipv6): disable

View BGP configuration:

To view BGP configuration on the device, use the following command:

->get vrouter <virtual router name> protocol bgp config  SSG140(M)-> get vrouter trust-vr proto bgp config  set protocol bgp 1111                                     >>AS number is configured  set enable
set neighbor 10.204.115.29 remote-as 1111 >>Defining the BGP neighbor,1111 is AS number for the peer 10.204.115.29  set neighbor 10.204.115.29 enable
unset ipv4 synchronization
set ipv4 neighbor 10.204.115.29 activate >>Activating the neighbor  set ipv4 network 5.5.5.0/24 no-check                      >>Defines the IP address of the network that is reachable from the BGP routing instance.  No-check disables the reachability check of the network from the virtual router  exit set interface ethernet0/0 protocol bgp                    >>Enable BGP at the interface which will communicate with the BGP peer 


View BGP neighbors:

To view the connection state between the BGP peers, you can use the following command

->get vrouter <virtual router name> protocol bgp neighbor  SSG140(M)-> get vr trust proto bgp neighbor  Peer AS Remote IP Local IP v4/v6Wt Status State ConnID Up/Down ------------------------------- 1111 10.204.115.29 0.0.0.0 100/ 100 Enabled IDLE 0 00:00:10  total 1 BGP peers shown



The above output shows the state as IDLE, which means that the firewall has initiated connection.

Note: Sometimes if an error occurs, the BGP session is terminated immediately and returned to the Idle state.

SSG140(M)-> get vrouter trust-vr protocol bgp neighbor

Peer AS Remote IP Local IP Wt Status State ConnID Up/Down
--------------------------------------------------------------------------------------
1111 10.204.115.29 0.0.0.0 100 Enabled ACTIVE 0 20:59:03

total 1 BGP peers shown


The above output shows the state as Active, which means that the TCP connection is Initiated.

SSG140(M)-> get vr trust proto bgp neighbor

Peer AS Remote IP Local IP v4/v6Wt Status State ConnID Up/Down
--------------------------------------------------------------------------------------
1111 10.204.115.29 10.204.115.56 100/ 100 Enabled ESTABLISH 22 00:00:04

total 1 BGP peers shown


The routing information is exchanged between the peers. In the Established state, the router can send/receive: Keepalive, Update, and Notification messages to/from its peer.

+Check Socket information:  SSG140(M)-> get socket
Socket Type State Remote IP Port Local IP Port
0 tcp4/6 listen :: 0 :: 80
1 tcp4/6 listen :: 0 :: 443
2 tcp4/6 listen :: 0 :: 23
15 tcp4/6 listen :: 0 :: 179
20 tcp open 10.204.115.29 179 10.204.115.56 7222 >>TCP port 179 is in open state    256 udp open :: 0 :: 546 257 udp open :: 0 :: 547 258 udp4/6 open :: 0 :: 500 259 udp4/6 open :: 0 :: 4500 260 udp4/6 open :: 0 :: 500 261 udp4/6 open :: 0 :: 4500 262 udp4/6 open :: 0 :: 123 263 udp open 0.0.0.0 0 0.0.0.0 161  Raw IP sockets: Socket Type Remote IP Local IP Protocol 512 raw 0.0.0.0 0.0.0.0 01h 513 raw :: :: 3ah 516 raw 0.0.0.0 0.0.0.0 01h 517 raw :: :: 3ah  Raw packet sockets: Socket Type Remote Mac Local Mac Protocol 514 eth 000000000000 000000000000 0806h 515 eth 000000000000 000000000000 8133h

+BGP neighbors, peers are established by manual configuration between routers to create a TCP session on port 179.
Hence check socket information specifically for port 179

SSG140(M)-> get socket | i 179

15 tcp4/6 listen :: 0 :: 179

20 tcp open 10.204.115.29 179 10.204.115.56 7222                      
             >>Check the socket ID 


+Issue the following command to check the detailed socket information with respect to socket ID.

SSG140(M)-> get socket id 20
socket 20, type tcp, state open                                       
             >>Check the socket state remote IP 10.204.115.29, port 179, local IP 10.204.115.56, port 7222, maxq 0, cnt 0, evt 0, ack_nbuf 0, snd_nbuf 0, sockp 61E3FB0     
             >>Verify the remote peer IP and Local peer IP. >>Also verify the source port and destination port socket options: main 0x20, udp 0x0, raw 0x0 src if: ethernet0/0                                                                 >>Check the source interface which has BGP enabled pak q: head 0, tail 0 and count 0, max delay 0 ms, deq 0, drop 0 pak q: head 0, tail 0 and count 0, max delay 0 ms, deq 0, drop 0       sock id 20(20), flag 1, mode 3, state ESTABLISH, if ethernet0/0, vsys Root              >>Check the socket / Application state ,here it is in ESTABLISHED state  vr trust-vr                                                                          >>Check the VR idle time 0, time wait status 0 timer status 0, retry 0/10, timeout 2000 local port 7222, remote port 10.204.115.29/179 iss -649745980, mss 1380 unack sn 3645221908, send sn 3645221908, last rcv ack 3645221908 send window 8173, w1/w2 385843646/3645221908 cwnd 65535  total in bytes 591, total out bytes 591 total in error 0, send data blocked 0                                                 >>Check for any error in nbuf 00000000, max rcv data 10                                                     >>Check the bytes received and sent emw close 0, context 00000000

+What to check in "get tcp":

SSG140(M)-> get tcp tcp checksum error: 0, tcp http ping: 0 >>Check if there is any checksum error tcp user auth: 0, tcp unknown port 0 tcp no more socket: 0, tcp syn pak error: 0 tcp socket full drop count: 0 tcp ooo segs: 0, tcp ooo segs drop count: 0 max ooo segs: 32, default max ooo segs 32 Total sock: 5/256, debug remote port: 65535 >>Check if the enough sockets are available +Debug logs:


SSG140(M)-> get db str ## 2011-07-11 17:16:17 : raw_pak_rcv: received 60 bytes ## 2011-07-11 17:16:17 : raw_pak_rcv: received 60 bytes ## 2011-07-11 17:16:17 : recvfrom: receive 60 bytes from 0019e248138d ## 2011-07-11 17:16:18 : recvfrom: receive 60 bytes from 0019e248138d ## 2011-07-11 17:16:24 : [bgp/peer]: restart timeout for peer 10.204.115.29 ## 2011-07-11 17:16:24 : socket: socket 21 opened (1,1,6) ## 2011-07-11 17:16:24 : [bgp/socket]: bgpNodeConnect peer afi 0 ## 2011-07-11 17:16:24 : [bgp/socket]: connecting to 10.204.115.29/179 via socket 21, designate local addr: 0.0.0.0 ## 2011-07-11 17:16:24 : [bgp/socket]: bgp enabled on interface (ethernet0/0) flag 0x6040108 ## 2011-07-11 17:16:24 : [bgp/socket]: bgp server socket bind peer_afi 0 addr 10.204.115.56 ## 2011-07-11 17:16:24 : bind: tcp sock 0, port 0 my_as ## 2011-07-11 17:16:24 : setsockopt: bind sock 21 to vr trust-vr ## 2011-07-11 17:16:24 : [bgp/socket]: update fd_select to 21 ## 2011-07-11 17:16:24 : sctrl: socket set to non-blocking mode ## 2011-07-11 17:16:24 : [bgp/socket]: Connect peer_afi 0 ## 2011-07-11 17:16:24 : connect(21): connection in progress ## 2011-07-11 17:16:24 : [bgp/event]: vr(trust-vr)/peer(10.204.115.29) FSM: IDLE --> CONNECT, event START >>The BGP state changes from IDLE to CONNECT
## 2011-07-11 17:16:24 : TCP(sock num 21,his port 179,sock state 2) >>TCP connection to the peer ## 2011-07-11 17:16:24 : => port (17900/179), seq 1087887847/ack 0, size 0 ## 2011-07-11 17:16:24 : SYN ## 2011-07-11 17:16:24 : TCP (sock num 21,sock state 2) -> Start timer ## 2011-07-11 17:16:25 : recvfrom: receive 60 bytes from 0019e248138d ## 2011-07-11 17:16:25 : recvfrom: receive 60 bytes from 0019e248138d ## 2011-07-11 17:16:26 : tcp_timeout ## 2011-07-11 17:16:26 : TCP(sock num 21,his port 179,sock state 2) ## 2011-07-11 17:16:26 : -- Timeout (0,10) ## 2011-07-11 17:16:26 : retry ## 2011-07-11 17:16:26 : TCP(sock num 21,his port 179,sock state 2) ## 2011-07-11 17:16:26 : => port (17900/179), seq 1087887847/ack 0, size 0 ## 2011-07-11 17:16:26 : SYN ## 2011-07-11 17:16:28 : SYN ## 2011-07-11 17:16:28 : TCP (sock num 21,sock state 2) -> Start timer ## 2011-07-11 17:16:33 : recvfrom: receive 60 bytes from 0019e248138d ## 2011-07-11 17:16:33 : recvfrom: receive 60 bytes from 0019e248138d ## 2011-07-11 17:16:33 : check socket bind: my (0.0.0.0-80), his (3.197.161.32 - 179) ## 2011-07-11 17:16:33 : check socket bind: my (0.0.0.0-443), his (3.197.161.32 - 179) ## 2011-07-11 17:16:33 : check socket bind: my (0.0.0.0-23), his (3.197.161.32 - 179) ## 2011-07-11 17:16:33 : tcp_input, id 15, mode 0, in_hash 0 ## 2011-07-11 17:16:33 : his port 0/pak port 52285, hisaddr 0.0.0.0/pakaddr 10.204.115.29, vsys 65684976/pakvsys 0, st 0 ## 2011-07-11 17:16:33 : socket: socket 22 opened (1,1,6) ## 2011-07-11 17:16:33 : create socket for connection request ## 2011-07-11 17:16:33 : Invoking pre-connect callback for socket 22 ## 2011-07-11 17:16:33 : [bgp/socket]: vr(trust-vr) conn request from peer 10.204.115.29 pass socket (22) check ## 2011-07-11 17:16:33 : Processing tcp option field, sockp 6c1c57c, tcphdr 3c5a124, iphdr 3c5a110 ## 2011-07-11 17:16:33 : tcpopt: type[2] len[4] bgp_md5_key_len[0]. ## 2011-07-11 17:16:33 : <- TCP packet MSS option value 1380 ## 2011-07-11 17:16:33 : queue the client socket, id:22 ## 2011-07-11 17:16:33 : :tcp_input_common,send syn ack for new connection request ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 3) ## 2011-07-11 17:16:33 : => port (179/52285), seq 1106106340/ack 2417560585, size 0 ## 2011-07-11 17:16:33 : SYN ## 2011-07-11 17:16:33 : TCP (sock num 22,sock state 3) -> Start timer ## 2011-07-11 17:16:33 : tcp_input, id 22, mode 2, in_hash 1 ## 2011-07-11 17:16:33 : his port 52285/pak port 52285, hisaddr 10.204.115.29/pakaddr 10.204.115.29, vsys 65684976/pakvsys 65684976, st 3 ## 2011-07-11 17:16:33 : Processing tcp option field, sockp 6c1c57c, tcphdr 3c5a124, iphdr 3c5a110 ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 3) ## 2011-07-11 17:16:33 : <= seq 2417560585/ack 1106106341,size 0 ## 2011-07-11 17:16:33 : ## 2011-07-11 17:16:33 : srv sock state:2,reader:31630984 ## 2011-07-11 17:16:33 : tcp_syn_rcv, wake up task :52 ## 2011-07-11 17:16:33 : select: data available with 15, socket state 2 ## 2011-07-11 17:16:33 : get a new connection,id:22, tcp id:22 ## 2011-07-11 17:16:33 : accept: connection from 10.204.115.29/52285 accepted ## 2011-07-11 17:16:33 : [bgp/socket]: accept session at socket 22, peer 10.204.115.29:52285 OK. ## 2011-07-11 17:16:33 : [bgp/socket]: update fd_select to 22 ## 2011-07-11 17:16:33 : [bgp/socket]: add peer ctrl blk: socket 22, direction: INCOMING ## 2011-07-11 17:16:33 : [bgp/peer]: Create duplicate peer: addr of new peer struct: 77871f0, addr of old peer struct : 6bd9670, peer ip: 10.204.115.29, local ip: 10.204.115.56 ## 2011-07-11 17:16:33 : [bgp/stack]: passive connect: dup skt 21 to skt 22 ## 2011-07-11 17:16:33 : [bgp/peer]: bgpOpenAddCaps cfg afi 1 ## 2011-07-11 17:16:33 : [bgp/peer]: MP_EXT Cap. for afi/safi: 1/1 is is added to peer: 10.204.115.29 ## 2011-07-11 17:16:33 : [bgp/peer]: bgpOpenAddCaps cfg afi 0 ## 2011-07-11 17:16:33 : [bgp/peer]: bgpOpenAddCaps cfg afi 0 ## 2011-07-11 17:16:33 : [bgp/peer]: bgpOpenAddCaps cfg afi 0 ## 2011-07-11 17:16:33 : [bgp/peer]: RT_REFRESH Cap. is added to peer: 10.204.115.29 ## 2011-07-11 17:16:33 : [bgp/peer]: CISCO old RT_REFRESH Cap. is added to peer: 10.204.115.29 ## 2011-07-11 17:16:33 : [bgp/peer]: Capabilities of length 10 are added to peer: 10.204.115.29 ## 2011-07-11 17:16:33 : [bgp/flow]: Sent OPEN msg with option length: 12 to peer: 10.204.115.29                >> Sending OPENSENT message to the BGP peer
## 2011-07-11 17:16:33 : [bgp/stack]: send OPEN msg to peer 10.204.115.29 via socket 22 ## 2011-07-11 17:16:33 : ->Request send (22) ## 2011-07-11 17:16:33 : send: 41 bytes send for socket 22 ## 2011-07-11 17:16:33 : [bgp/flow]: send 41 bytes to sock 22 07801948: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ........ ........ 07801958: 00 29 01 04 04 57 00 b4 c0 a8 01 02 0c 02 0a 01 .)...W.. ........ 07801968: 04 00 01 00 01 02 00 80 00 ........ . ## 2011-07-11 17:16:33 : [bgp/event]: vr(trust-vr)/peer(10.204.115.29) FSM: ACTIVE --> OPEN_SENT, event TCP_OPEN                          
               >>TCP connection is initiated and waiting for peer's reply. Hence ACTIVE state.Once the reply is received from the peer ,state changes to OPENSENT

## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : => port (179/52285), seq 1106106341/ack 2417560585, size 41 ## 2011-07-11 17:16:33 : PSH ## 2011-07-11 17:16:33 : TCP (sock num 22,sock state 4) -> Start timer ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : send data too small(0)! unack(1106106341) send_next(1106106382) ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : => err 0, send flag 0000 event 0008 ## 2011-07-11 17:16:33 : tcp_input, id 22, mode 2, in_hash 1 ## 2011-07-11 17:16:33 : his port 52285/pak port 52285, hisaddr 10.204.115.29/pakaddr 10.204.115.29, vsys 65684976/pakvsys 65684976, st 4 ## 2011-07-11 17:16:33 : Processing tcp option field, sockp 6c1c57c, tcphdr 3c5a504, iphdr 3c5a4f0 ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : <= seq 2417560585/ack 1106106341,size 41 ## 2011-07-11 17:16:33 : PSH ## 2011-07-11 17:16:33 : ,diff=0 ## 2011-07-11 17:16:33 : (0) ## 2011-07-11 17:16:33 : <- Receive data notify (22) ## 2011-07-11 17:16:33 : <- Receive data notify (22) in_buf false, sock->reader 1e2a688 sock->select_cb 5cc440 ## 2011-07-11 17:16:33 : [bgp/peer]: rt-refresh cap. received from peer: 10.204.115.29 ## 2011-07-11 17:16:33 : [bgp/stack]: send KEEPALIVE msg to peer 10.204.115.29 via socket 22                  >>Keepalive messages are sent to the peer
## 2011-07-11 17:16:33 : ->Request send (22) ## 2011-07-11 17:16:33 : send: 19 bytes send for socket 22 ## 2011-07-11 17:16:33 : [bgp/flow]: send 19 bytes to sock 22 03eb7ba8: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ........ ........ 03eb7bb8: 00 13 04 ... ## 2011-07-11 17:16:33 : [bgp/event]: vr(trust-vr)/peer(10.204.115.29) FSM: OPEN_SENT --> OPEN_CONF, event RX_OPEN   >>Open confirm state
## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : send data too small(19)! unack(1106106341) send_next(1106106382) ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : => port (179/52285), seq 1106106382/ack 2417560626, size 0 ## 2011-07-11 17:16:33 : PSH ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : send data too small(19)! unack(1106106341) send_next(1106106382) ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : => err 0, send flag 0000 event 0008 ## 2011-07-11 17:16:33 : tcp_input, id 22, mode 2, in_hash 1 ## 2011-07-11 17:16:33 : his port 52285/pak port 52285, hisaddr 10.204.115.29/pakaddr 10.204.115.29, vsys 65684976/pakvsys 65684976, st 4 ## 2011-07-11 17:16:33 : Processing tcp option field, sockp 6c1c57c, tcphdr 3c5a504, iphdr 3c5a4f0 ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : <= seq 2417560626/ack 1106106382,size 0 ## 2011-07-11 17:16:33 : PSH ## 2011-07-11 17:16:33 : ,diff=41 ## 2011-07-11 17:16:33 : ,+41 ## 2011-07-11 17:16:33 : (0) ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : => port (179/52285), seq 1106106382/ack 2417560626, size 19 ## 2011-07-11 17:16:33 : PSH ## 2011-07-11 17:16:33 : TCP (sock num 22,sock state 4) -> Start timer ## 2011-07-11 17:16:33 : tcp_input, id 22, mode 2, in_hash 1 ## 2011-07-11 17:16:33 : recv: 19 bytes received for socket (22) ## 2011-07-11 17:16:33 : [bgp/flow]: received 19 bytes from socket 22 06737de0: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ........ ........ 06737df0: 00 13 04 ... ## 2011-07-11 17:16:33 : [bgp/stack]: Rx 10.204.115.29: KEEPALIVE msg, conn-id 22       >> Keepalive messages are received from the peer
## 2011-07-11 17:16:33 : [bgp/peer]: Find Dup PeerOK: cb addr: 6bd9670 ## 2011-07-11 17:16:33 : [bgp/socket]: Established peer to close the dup peer ## 2011-07-11 17:16:33 : [bgp/peer]: close socket 21 for peer 10.204.115.29 ## 2011-07-11 17:16:33 : [bgp/socket]: cannot find peer ctrl blk to invalidate: socket 21 ## 2011-07-11 17:16:33 : close: socket id 21 ## 2011-07-11 17:16:33 : ->Request close (21) ## 2011-07-11 17:16:33 : [bgp/socket]: delete dup cb 10.204.115.29's pending connection 21 ## 2011-07-11 17:16:33 : [bgp/socket]: update fd_select to 22 ## 2011-07-11 17:16:33 : [bgp/peer]: peer 10.204.115.29 start purge rib-in      >> Updates are exchanged
## 2011-07-11 17:16:33 : [bgp/peer]: peer 10.204.115.29 start purge rib-in ## 2011-07-11 17:16:33 : [bgp/peer]: peer 10.204.115.29 ribin purged done ## 2011-07-11 17:16:33 : [bgp/error]: Queue UnLink no match: addr of peer struct: 6bd9670, cb_id: 10.204.115.29 ## 2011-07-11 17:16:33 : [bgp/snmp]: bgp send snmp trap for backward transition: vr: 2, peer: 10.204.115.29, state: 1, error: 6 0 ## 2011-07-11 17:16:33 : [bgp/peer]: Find Dup PeerOK: cb addr: 77871f0 ## 2011-07-11 17:16:33 : [bgp/peer]: Free CB addr: 6bd9670, remote ip addr: 10.204.115.29, local ip addr: 0.0.0.0 ## 2011-07-11 17:16:33 : [bgp/peer]: Peer Free: delete bgp peer cb addr: 6bd9670 ## 2011-07-11 17:16:33 : [bgp/snmp]: snd snmpTrap: vr(trust-vr) / peer 10.204.115.29 / state ESTABLISH / error(0:0) ## 2011-07-11 17:16:33 : [bgp/basic]: bgp session to Peer(10.204.115.29) established ## 2011-07-11 17:16:33 : [bgp/event]: vr(trust-vr)/peer(10.204.115.29) FSM: OPEN_CONF --> ESTABLISH, event RX_KEEP >> Connection Established successfully
## 2011-07-11 17:16:33 : TCP(sock num 21,his port 179,sock state 2) ## 2011-07-11 17:16:33 : => port (17900/179), seq 1087887848/ack 0, size 0 ## 2011-07-11 17:16:33 : RST ## 2011-07-11 17:16:33 : -- Freeing TCP sock ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : send data too small(0)! unack(1106106382) send_next(1106106401) ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : => port (179/52285), seq 1106106401/ack 2417560645, size 0 ## 2011-07-11 17:16:33 : PSH ## 2011-07-11 17:16:33 : tcp_input, id 22, mode 2, in_hash 1 ## 2011-07-11 17:16:33 : his port 52285/pak port 52285, hisaddr 10.204.115.29/pakaddr 10.204.115.29, vsys 65684976/pakvsys 65684976, st 4 ## 2011-07-11 17:16:33 : Processing tcp option field, sockp 6c1c57c, tcphdr 3c5a124, iphdr 3c5a110 ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : <= seq 2417560645/ack 1106106401,size 0 ## 2011-07-11 17:16:33 : PSH ## 2011-07-11 17:16:33 : ,diff=19 ## 2011-07-11 17:16:33 : ,+19 ## 2011-07-11 17:16:33 : (0) ## 2011-07-11 17:16:33 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:33 : => err 0, send flag 0000 event 0008 ## 2011-07-11 17:16:34 : [bgp/update]: iBGP update prefix: 5.5.5.0/24 ## 2011-07-11 17:16:34 : [bgp/update]: nhop = 0 ## 2011-07-11 17:16:34 : [bgp/update]: Build Tx PA: med: 0, local pref: 100, nhop: 10.204.115.56 ## 2011-07-11 17:16:34 : [bgp/update]: Build Tx PA proc internal peer: med: 0 ## 2011-07-11 17:16:34 : [bgp/update]: add feasible afi/safi: 1/1 prefix 5.5.5.0/24 to peer 10.204.115.29 Tx-q ## 2011-07-11 17:16:34 : [bgp/stack]: Txq enqueue UPDATE msg to peer 10.204.115.29 via connID 22 palen 21 reachlen 4 unreachlen 48 ## 2011-07-11 17:16:34 : ->Request send (22) ## 2011-07-11 17:16:34 : send: 48 bytes send for socket 22 ## 2011-07-11 17:16:34 : [bgp/flow]: send 48 bytes to sock 22 06c20bb0: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ........ ........ 06c20bc0: 00 30 02 00 00 00 15 40 01 01 00 40 02 00 40 03 .0.....@ ...@..@. 06c20bd0: 04 0a cc 73 38 40 05 04 00 00 00 64 18 05 05 05 ...s8@.. ...d.... ## 2011-07-11 17:16:34 : [bgp/tic]: InitialSend ## 2011-07-11 17:16:34 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:34 : => port (179/52285), seq 1106106401/ack 2417560645, size 48 ## 2011-07-11 17:16:34 : PSH ## 2011-07-11 17:16:34 : TCP (sock num 22,sock state 4) -> Start timer ## 2011-07-11 17:16:34 : tcp_input, id 22, mode 2, in_hash 1 ## 2011-07-11 17:16:34 : his port 52285/pak port 52285, hisaddr 10.204.115.29/pakaddr 10.204.115.29, vsys 65684976/pakvsys 65684976, st 4 ## 2011-07-11 17:16:34 : Processing tcp option field, sockp 6c1c57c, tcphdr 3c5a504, iphdr 3c5a4f0 ## 2011-07-11 17:16:34 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:34 : <= seq 2417560645/ack 1106106449,size 75 ## 2011-07-11 17:16:34 : PSH ## 2011-07-11 17:16:34 : ,diff=48 ## 2011-07-11 17:16:34 : ,+48 ## 2011-07-11 17:16:34 : (0) ## 2011-07-11 17:16:34 : <- Receive data notify (22) ## 2011-07-11 17:16:34 : <- Receive data notify (22) in_buf false, sock->reader 1e2a688 sock->select_cb 5cc440 ## 2011-07-11 17:16:34 : peek: 2 bytes received for socket (22) ## 2011-07-11 17:16:34 : peek: 2 bytes received for socket (22) ## 2011-07-11 17:16:34 : peek: 0 bytes received for socket (22) ## 2011-07-11 17:16:34 : [bgp/socket]: bgp socket (22) check length callback#packets: 2, multi pak-len 75, pending-len 75 ## 2011-07-11 17:16:34 : peek: 2 bytes received for socket (22) ## 2011-07-11 17:16:34 : peek: 2 bytes received for socket (22) ## 2011-07-11 17:16:34 : peek: 0 bytes received for socket (22) ## 2011-07-11 17:16:34 : [bgp/socket]: bgp socket (22) check length callback#packets: 2, multi pak-len 75, pending-len 75 ## 2011-07-11 17:16:34 : select: data available with 22, socket state 1 ## 2011-07-11 17:16:34 : [bgp/socket]: try to receive 75 bytes from socket 22 ## 2011-07-11 17:16:34 : recv: 75 bytes received for socket (22) ## 2011-07-11 17:16:34 : [bgp/flow]: received 75 bytes from socket 22 06737de0: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ........ ........ 06737df0: 00 1b 02 00 04 18 05 05 05 00 00 ff ff ff ff ff ........ ........ 06737e00: ff ff ff ff ff ff ff ff ff ff ff 00 30 02 00 00 ........ ....0... 06737e10: 00 15 40 01 01 00 40 02 00 40 03 04 0a cc 73 1d ..@...@. .@....s. 06737e20: 40 05 04 00 00 00 64 18 06 06 06 @.....d. ... ## 2011-07-11 17:16:34 : [bgp/stack]: Rx 10.204.115.29: UPDATE msg, conn-id 22 ## 2011-07-11 17:16:34 : [bgp/update]: validate/canonical UPDATE pass ## 2011-07-11 17:16:34 : [bgp/update]: start: proc updt msg, peer:10.204.115.29 ## 2011-07-11 17:16:34 : [bgp/update]: done : proc updt msg, peer:10.204.115.29 ## 2011-07-11 17:16:34 : [bgp/stack]: Rx 10.204.115.29: UPDATE msg, conn-id 22 ## 2011-07-11 17:16:34 : [bgp/diag]: bgp receive total pa length 21 ## 2011-07-11 17:16:34 : [bgp/update]: created new PA, peer 10.204.115.29 ## 2011-07-11 17:16:34 : [bgp/update]: validate/canonical UPDATE pass ## 2011-07-11 17:16:34 : [bgp/update]: start: proc updt msg, peer:10.204.115.29 ## 2011-07-11 17:16:34 : [bgp/rtmap]: start: apply policy route 0.0.0.0/0, peer 10.204.115.29, rtmap NULL ## 2011-07-11 17:16:34 : [bgp/rtmap]: done : apply policy route 0.0.0.0/0, peer 10.204.115.29, rtmap NULL ## 2011-07-11 17:16:34 : [bgp/update]: start: proc nlri 6.6.6.0/24 ## 2011-07-11 17:16:34 : [bgp/update]: local rib add prefix 6.6.6.0 / pref 100, metric 0, next-hop 10.204.115.29 ## 2011-07-11 17:16:34 : [bgp/nexthop]: added nexthop for BGP nexthop: 10.204.115.29 with fdb nexthop: 10.204.115.29, cost: 0, reach_addr: 10.204.115.56, reach_mask: 24, bgprt: 0 ## 2011-07-11 17:16:34 : [bgp/update]: add 6.6.6.0/24 to intchg ## 2011-07-11 17:16:34 : [bgp/update]: done : proc nlri 6.6.6.0/24 OK ## 2011-07-11 17:16:34 : [bgp/update]: done : proc updt msg, peer:10.204.115.29 ## 2011-07-11 17:16:34 : [bgp/update]: start: send iBGP updates ## 2011-07-11 17:16:34 : [bgp/update]: proc iBGP update prefix: 6.6.6.0/24 ## 2011-07-11 17:16:34 : [bgp/update]: Add Withd_rt 6.6.6.0/24 to peer 10.204.115.29 Tx_q ## 2011-07-11 17:16:34 : [bgp/update]: add unreachable afi/safi: 1/1 prefix 6.6.6.0/24 to peer 10.204.115.29 Tx-q ## 2011-07-11 17:16:34 : [bgp/stack]: Txq enqueue UPDATE msg to peer 10.204.115.29 via connID 22 palen 0 reachlen 0 unreachlen 27 ## 2011-07-11 17:16:34 : [bgp/update]: done : send iBGP updates ## 2011-07-11 17:16:34 : [bgp/update]: start: update FDB CHUNKABLE run ## 2011-07-11 17:16:34 : [bgp/update]: update FDB for 6.6.6.0/24 flags 0x9 ## 2011-07-11 17:16:34 : [bgp/route]: best route does not exist: 6.6.6.0/24 ## 2011-07-11 17:16:34 : [bgp/route]: vr(trust-vr) add route 6.6.6.0/24->10.204.115.29 cost 0 flag 8000000 interface ethernet0/0 ## 2011-07-11 17:16:34 : [bgp/update]: update FDB, prepend 6.6.6.0/24 to extchg list ## 2011-07-11 17:16:34 : [bgp/update]: done : update FDB COMPLETED run, no sync fail ## 2011-07-11 17:16:34 : ->Request send (22) ## 2011-07-11 17:16:34 : send: 27 bytes send for socket 22 ## 2011-07-11 17:16:34 : [bgp/flow]: send 27 bytes to sock 22 06bbd464: ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ........ ........ 06bbd474: 00 1b 02 00 04 18 06 06 06 00 00 ........ ... ## 2011-07-11 17:16:34 : [bgp/basic]: start: send ipv4 eBGP updates ## 2011-07-11 17:16:34 : [bgp/update]: eBGP update prefix: 6.6.6.0/24 clflags 0 ## 2011-07-11 17:16:34 : [bgp/update]: done : send eBGP updates OK ## 2011-07-11 17:16:34 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:34 : => port (179/52285), seq 1106106449/ack 2417560720, size 27 ## 2011-07-11 17:16:34 : PSH ## 2011-07-11 17:16:34 : TCP (sock num 22,sock state 4) -> Start timer ## 2011-07-11 17:16:34 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:34 : send data too small(0)! unack(1106106449) send_next(1106106476) ## 2011-07-11 17:16:34 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:34 : => err 0, send flag 0000 event 0008 ## 2011-07-11 17:16:34 : raw_pak_rcv: received 60 bytes ## 2011-07-11 17:16:34 : recvfrom: receive 60 bytes from 0019e248138d ## 2011-07-11 17:16:34 : tcp_input, id 22, mode 2, in_hash 1 ## 2011-07-11 17:16:34 : his port 52285/pak port 52285, hisaddr 10.204.115.29/pakaddr 10.204.115.29, vsys 65684976/pakvsys 65684976, st 4 ## 2011-07-11 17:16:34 : Processing tcp option field, sockp 6c1c57c, tcphdr 3c5a124, iphdr 3c5a110 ## 2011-07-11 17:16:34 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:34 : <= seq 2417560720/ack 1106106476,size 0 ## 2011-07-11 17:16:34 : PSH ## 2011-07-11 17:16:34 : ,diff=27 ## 2011-07-11 17:16:34 : ,+27 ## 2011-07-11 17:16:34 : (0) ## 2011-07-11 17:16:34 : TCP(sock num 22,his port 52285,sock state 4) ## 2011-07-11 17:16:34 : => err 0, send flag 0000 event 0008 ## 2011-07-11 17:16:34 : raw_pak_rcv: received 60 bytes >>These are all socket output ## 2011-07-11 17:16:34 : raw_pak_rcv: received 60 bytes ## 2011-07-11 17:16:34 : recvfrom: receive 60 bytes from 0019e248138d ## 2011-07-11 17:16:34 : recvfrom: receive 60 bytes from 0019e248138d ## 2011-07-11 17:16:35 : raw_pak_rcv: received 60 bytes ## 2011-07-11 17:16:35 : raw_pak_rcv: received 60 bytes ## 2011-07-11 17:16:35 : recvfrom: receive 60 bytes from 0019e248138d ## 2011-07-11 17:16:35 : recvfrom: receive 60 bytes from 0019e248138d ## 2011-07-11 17:16:36 : raw_pak_rcv: received 60 bytes
Related Links: