Flow spec question

743 views

Skip to first unread message

Quentin Loos

unread,

Sep 30, 2013, 3:21:21 AM9/30/13

to exabgp...@googlegroups.com

Hi,

I would like to develop an application that can mitigate DDoS attack via BGP flow spec. For that purpose, I would like to use ExaBGP for distribute flow rules in the entire AS. I have multiple questions :

1. What is the best way to dynamically inject and remove BGP flow rule with ExaBGP ? I don't want to use configuration files fort that (just for the BGP session).

2. I would like to verify that rules are well propagated. How can I do that ?
If I set up an iBGP session (in addition to eBGP session) between exaBGP and the router, can I use ExaBGP to verify that a BGP update with the flow is received via the iBGP ?

Thanks,
Quentin

Thomas Mangin

unread,

Sep 30, 2013, 4:37:23 AM9/30/13

to exabgp...@googlegroups.com

On 30 Sep 2013, at 08:21, Quentin Loos <quenti...@gmail.com> wrote:

> 1. What is the best way to dynamically inject and remove BGP flow rule with ExaBGP ? I don't want to use configuration files fort that (just for the BGP session).

The very badly documented API is the way to go. You can look at the examples in etc/exabgp and dev/runtest

> 2. I would like to verify that rules are well propagated. How can I do that ?

Please look at the various FlowSpec presentation on the web (some linked from the wiki).
JunOS has some command to see the installed flow routes.

> If I set up an iBGP session (in addition to eBGP session) between exaBGP and the router, can I use ExaBGP to verify that a BGP update with the flow is received via the iBGP ?

ExaBGP since 3.2 stable can now decode FlowSpec messages, so yes, you can use it to make sure your router got the update.

Thomas

Quentin Loos

unread,

Sep 30, 2013, 5:02:42 AM9/30/13

to exabgp...@googlegroups.com

The very badly documented API is the way to go. You can look at the examples in etc/exabgp and dev/runtest

Ok, I will look about that, thank you ;)

Please look at the various FlowSpec presentation on the web (some linked from the wiki).
JunOS has some command to see the installed flow routes.

Yeah sure, but I want do that properly in the application.

ExaBGP since 3.2 stable can now decode FlowSpec messages, so yes, you can use it to make sure your router got the update.

Ok, i think it's the best solution. How can i retrieve this information from exaBGP ?

I have another problem. I try to inject a flow in a juniper device (JunOS 10.4R5.5). This is my exaBGP configuration :

neighbor 10.16.0.132 {
    description "m120-test";
    local-address 10.4.2.90;
    local-as 65406;
    peer-as 2611;
    graceful-restart 5;

    family {
        ipv4 unicast;
        ipv4 flow;
    }

    flow {
        route test {
            match {
                source 10.0.0.5/32;  
            }
            then {
                discard;
            }
        }
    }
}

I configured my juniper device to accept flow. I can't inject flow with exaBGP.
I can set flow in the configuration manually, and i can inject static route with exaBGP.

Here, the output of exaBGP :

Mon, 30 Sep 2013 11:01:00 | INFO     | 16040  | reactor       | Performing reload of exabgp 3.2.13
Mon, 30 Sep 2013 11:01:00 | INFO     | 16040  | reactor       | New Peer neighbor 10.16.0.132 local-ip 10.4.2.90 local-as 65406 peer-as 2611 router-id 10.4.2.90 family-allowed in-open
Mon, 30 Sep 2013 11:01:01 | INFO     | 16040  | network       | Connected to peer neighbor 10.16.0.132 local-ip 10.4.2.90 local-as 65406 peer-as 2611 router-id 10.4.2.90 family-allowed in-open (out)
Mon, 30 Sep 2013 11:01:02 | INFO     | 16040  | network       | Peer     10.16.0.132 ASN 2611    out loop reset closing connection 
Mon, 30 Sep 2013 11:01:04 | INFO     | 16040  | network       | Connected to peer neighbor 10.16.0.132 local-ip 10.4.2.90 local-as 65406 peer-as 2611 router-id 10.4.2.90 family-allowed in-open (out)
Mon, 30 Sep 2013 11:01:05 | INFO     | 16040  | network       | Peer     10.16.0.132 ASN 2611    out loop reset closing connection

The Juniper device send me a NOTIFICATION message, with update error, Missing Well-known Attribute.

Have you a clue for this problem ?

Thomas Mangin

unread,

Sep 30, 2013, 5:10:23 AM9/30/13

to exabgp...@googlegroups.com

ExaBGP since 3.2 stable can now decode FlowSpec messages, so yes, you can use it to make sure your router got the update.

Ok, i think it's the best solution. How can i retrieve this information from exaBGP ?

No one asked for this feature, it was coded to make sure our generation code sent what we expected.

I do not believe I have hooked this back to the API yet ... no one ever asked for

The Juniper device send me a NOTIFICATION message, with update error, Missing Well-known Attribute.

Have you a clue for this problem ?

Could you please run ExaBGP with the -d option and give me again the output as no I have not seen it.

Thomas

Quentin Loos

unread,

Sep 30, 2013, 5:16:48 AM9/30/13

to exabgp...@googlegroups.com

Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | environment file missing
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | generate it using "exabgp -fi > /usr/local/etc/exabgp/exabgp.env"
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | reactor       | Performing reload of exabgp 3.2.13
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | neighbor 10.16.0.132 {
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |     description "m120-2.test.belnet.net";
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #    router-id 10.0.0.1;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |     local-address 10.4.2.90;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |     local-as 65406;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |     peer-as 2611;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |     graceful-restart 5;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |     family {
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |         ipv4 unicast;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |         ipv4 flow;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |     }
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |     flow {
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |         route test {
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |             match {
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                source 10.0.0.5/32;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                destination 192.168.0.1/32;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                port =80 =8080;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                destination-port >8080&<8088 =3128;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                source-port >1024;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |                 protocol icmp;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                protocol [ tcp udp ];
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                packet-length >200&<300 >400&<500;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                fragment not-a-fragment;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                fragment [ first-fragment last-fragment ];
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                icmp-type [ unreachable echo-request echo-reply ];
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                icmp-code [ host-unreachable network-unreachable ];
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                tcp-flags [ urgent rst ];
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                dscp [ 10 20 ];
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |             }
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |             then {
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                rate-limit 9600;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |                 discard;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                redirect 65500:12345;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                redirect 1.2.3.4:5678;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | #                community [30740:0 30740:30740];
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |             }
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |         }
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |     }
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading | }
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | loading |
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['neighbor', '10.16.0.132', '{']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['group', 'neighbor']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    []
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['description', '"m120-2.test.belnet.net"', ';']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['static', 'flow', 'process', 'family', 'capability', 'operational']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['description', 'router-id', 'local-address', 'local-as', 'peer-as', 'passive', 'hold-time', 'add-path', 'graceful-restart', 'md5', 'ttl-security', 'multi-session', 'group-updates', 'asn4', 'aigp']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['local-address', '10.4.2.90', ';']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['static', 'flow', 'process', 'family', 'capability', 'operational']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['description', 'router-id', 'local-address', 'local-as', 'peer-as', 'passive', 'hold-time', 'add-path', 'graceful-restart', 'md5', 'ttl-security', 'multi-session', 'group-updates', 'asn4', 'aigp']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['local-as', '65406', ';']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['static', 'flow', 'process', 'family', 'capability', 'operational']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['description', 'router-id', 'local-address', 'local-as', 'peer-as', 'passive', 'hold-time', 'add-path', 'graceful-restart', 'md5', 'ttl-security', 'multi-session', 'group-updates', 'asn4', 'aigp']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['peer-as', '2611', ';']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['static', 'flow', 'process', 'family', 'capability', 'operational']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['description', 'router-id', 'local-address', 'local-as', 'peer-as', 'passive', 'hold-time', 'add-path', 'graceful-restart', 'md5', 'ttl-security', 'multi-session', 'group-updates', 'asn4', 'aigp']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['graceful-restart', '5', ';']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['static', 'flow', 'process', 'family', 'capability', 'operational']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['description', 'router-id', 'local-address', 'local-as', 'peer-as', 'passive', 'hold-time', 'add-path', 'graceful-restart', 'md5', 'ttl-security', 'multi-session', 'group-updates', 'asn4', 'aigp']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['family', '{']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['static', 'flow', 'process', 'family', 'capability', 'operational']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['description', 'router-id', 'local-address', 'local-as', 'peer-as', 'passive', 'hold-time', 'add-path', 'graceful-restart', 'md5', 'ttl-security', 'multi-session', 'group-updates', 'asn4', 'aigp']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['ipv4', 'unicast', ';']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options []
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['inet', 'inet4', 'inet6', 'ipv4', 'ipv6', 'minimal', 'all']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['ipv4', 'flow', ';']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options []
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['inet', 'inet4', 'inet6', 'ipv4', 'ipv6', 'minimal', 'all']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['}']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options []
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['inet', 'inet4', 'inet6', 'ipv4', 'ipv6', 'minimal', 'all']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['flow', '{']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['static', 'flow', 'process', 'family', 'capability', 'operational']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['description', 'router-id', 'local-address', 'local-as', 'peer-as', 'passive', 'hold-time', 'add-path', 'graceful-restart', 'md5', 'ttl-security', 'multi-session', 'group-updates', 'asn4', 'aigp']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['route', 'test', '{']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['route']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    []
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['match', '{']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['match', 'then']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['rd', 'route-distinguisher', 'next-hop']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['protocol', 'icmp', ';']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options []
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['source', 'destination', 'port', 'source-port', 'destination-port', 'protocol', 'next-header', 'tcp-flags', 'icmp-type', 'icmp-code', 'fragment', 'dscp', 'traffic-class', 'packet-length', 'flow-label']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['}']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options []
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['source', 'destination', 'port', 'source-port', 'destination-port', 'protocol', 'next-header', 'tcp-flags', 'icmp-type', 'icmp-code', 'fragment', 'dscp', 'traffic-class', 'packet-length', 'flow-label']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['then', '{']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['match', 'then']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['rd', 'route-distinguisher', 'next-hop']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['discard', ';']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options []
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['discard', 'rate-limit', 'redirect', 'copy', 'redirect-to-nexthop', 'mark', 'action', 'community']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['}']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options []
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['discard', 'rate-limit', 'redirect', 'copy', 'redirect-to-nexthop', 'mark', 'action', 'community']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['}']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['match', 'then']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['rd', 'route-distinguisher', 'next-hop']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | warning: no check on flows are implemented
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['}']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['route']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    []
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | analysing tokens ['}']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid block options ['static', 'flow', 'process', 'family', 'capability', 'operational']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   valid parameters    ['description', 'router-id', 'local-address', 'local-as', 'peer-as', 'passive', 'hold-time', 'add-path', 'graceful-restart', 'md5', 'ttl-security', 'multi-session', 'group-updates', 'asn4', 'aigp']
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | Peer configuration complete :
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |    local-as: '65406'
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |    local-address: '10.4.2.90'
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |    families: '(1, 1)'
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |    families: '(1, 133)'
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |    description: 'm120-2.test.belnet.net'
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |    peer-as: '2611'
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |    peer-address: '10.16.0.132'
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |    announce: 'flow protocol =ICMP extended-community discard'
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |    graceful-restart: '5'
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | neighbor 10.16.0.132 {
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   description "m120-2.test.belnet.net";
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   router-id 10.4.2.90;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   local-address 10.4.2.90;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   local-as 65406;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   peer-as 2611;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   hold-time 180;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   capability {
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |     asn4 enable;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |     graceful-restart 5;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   }
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   family {
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |     inet4 unicast;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |     inet4 flow;
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   }
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   process {
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |   }
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration | }
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | configuration |
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | reactor       | New Peer neighbor 10.16.0.132 local-ip 10.4.2.90 local-as 65406 peer-as 2611 router-id 10.4.2.90 family-allowed in-open
Mon, 30 Sep 2013 11:13:48 | WARNING | 17230 | configuration | Loaded new configuration successfully
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop, intialising
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | wire          | Attempting connection to 10.16.0.132
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          SENDING FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0043 0104 FF7E 00B4 0A04 025A 2602 0C40 0A80 0500 0185 8000 0101 8002 0601 0400 0100 0102 0601 0400 0100 8502 0641 0400 00FF 7E
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop immediate callback, state is connect
Mon, 30 Sep 2013 11:13:48 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    >> OPEN version=4 asn=65406 hold_time=180 router_id=10.4.2.90 capabilities=[Graceful Restart, Multiprotocol(ipv4 unicast,ipv4 flow), 4Bytes AS 65406]
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 59 second(s) left
Mon, 30 Sep 2013 11:13:48 | DEBUG    | 17230 | network       | out loop      when possible, state is opensent
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0043 01
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED 040A 3300 5A0A 0000 7A26 0206 0104 0001 0001 0206 0104 0001 0085 0202 8000 0202 0200 0204 4002 0078 0206 4104 0000 0A33
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << OPEN version=4 asn=2611 hold_time=90 router_id=10.0.0.122 capabilities=[Cisco Route Refresh, Multiprotocol(ipv4 unicast,ipv4 flow), Route Refresh, Graceful Restart, 4Bytes AS 2611]
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 59 second(s) left
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          SENDING FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0013 04
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is openconfirm
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    >> KEEPALIVE (OPENCONFIRM)
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is openconfirm
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0013 04
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << KEEPALIVE (ESTABLISHED)
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 89 second(s) left
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is openconfirm
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | network       | Connected to peer neighbor 10.16.0.132 local-ip 10.4.2.90 local-as 65406 peer-as 2611 router-id 10.4.2.90 family-allowed in-open (out)
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0013 04
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << KEEPALIVE
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 89 second(s) left
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Sending Timer 29 second(s) left
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          SENDING FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0023 0200 0000 0C80 0E09 0001 8500 0003 0381 01
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    >> 1 UPDATE(s)
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0017 02
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED 0000 0000
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << UPDATE
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 89 second(s) left
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Sending Timer 29 second(s) left
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          SENDING FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 001E 0200 0000 0790 0F00 0300 0101
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          SENDING FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 001E 0200 0000 0790 0F00 0300 0185
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | message       | peer 10.16.0.132 ASN 2611    >> EOR(s)
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 001E 02
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED 0000 0007 900F 0003 0001 85
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << UPDATE
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 89 second(s) left
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | routes        | peer 10.16.0.132 ASN 2611    eor 1/133 (ipv4 flow)
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Sending Timer 29 second(s) left
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0017 03
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED 0303 0100
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << NOTIFICATION
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 89 second(s) left
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Sending Timer 29 second(s) left
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing, closing connection from 10.4.2.90 to 10.16.0.132
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing 10.16.0.132 lost TCP session with peer
Mon, 30 Sep 2013 11:13:49 | INFO     | 17230 | network       | Peer     10.16.0.132 ASN 2611    out loop reset closing connection
Mon, 30 Sep 2013 11:13:49 | DEBUG    | 17230 | wire          | session 1 outgoing, closing connection from 10.4.2.90 to 10.16.0.132
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop, intialising
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | wire          | Attempting connection to 10.16.0.132
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is idle
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          SENDING FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0043 0104 FF7E 00B4 0A04 025A 2602 0C40 0A80 0500 0185 8000 0101 8002 0601 0400 0100 0102 0601 0400 0100 8502 0641 0400 00FF 7E
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop immediate callback, state is connect
Mon, 30 Sep 2013 11:13:50 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    >> OPEN version=4 asn=65406 hold_time=180 router_id=10.4.2.90 capabilities=[Graceful Restart, Multiprotocol(ipv4 unicast,ipv4 flow), 4Bytes AS 65406]
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 59 second(s) left
Mon, 30 Sep 2013 11:13:50 | DEBUG    | 17230 | network       | out loop      when possible, state is opensent
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0043 01
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED 040A 3300 5A0A 0000 7A26 0206 0104 0001 0001 0206 0104 0001 0085 0202 8000 0202 0200 0204 4002 0078 0206 4104 0000 0A33
Mon, 30 Sep 2013 11:13:51 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << OPEN version=4 asn=2611 hold_time=90 router_id=10.0.0.122 capabilities=[Cisco Route Refresh, Multiprotocol(ipv4 unicast,ipv4 flow), Route Refresh, Graceful Restart, 4Bytes AS 2611]
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 59 second(s) left
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          SENDING FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0013 04
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | network       | out loop immediate callback, state is openconfirm
Mon, 30 Sep 2013 11:13:51 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    >> KEEPALIVE (OPENCONFIRM)
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | network       | out loop immediate callback, state is openconfirm
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0013 04
Mon, 30 Sep 2013 11:13:51 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << KEEPALIVE (ESTABLISHED)
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 89 second(s) left
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | network       | out loop immediate callback, state is openconfirm
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:51 | INFO     | 17230 | network       | Connected to peer neighbor 10.16.0.132 local-ip 10.4.2.90 local-as 65406 peer-as 2611 router-id 10.4.2.90 family-allowed in-open (out)
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 89 second(s) left
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Sending Timer 29 second(s) left
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          SENDING FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0023 0200 0000 0C80 0E09 0001 8500 0003 0381 01
Mon, 30 Sep 2013 11:13:51 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    >> 1 UPDATE(s)
Mon, 30 Sep 2013 11:13:51 | DEBUG    | 17230 | network       | out loop      when possible, state is established
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0013 04
Mon, 30 Sep 2013 11:13:52 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << KEEPALIVE
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 89 second(s) left
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Sending Timer 28 second(s) left
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          SENDING FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 001E 0200 0000 0790 0F00 0300 0101
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          SENDING FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 001E 0200 0000 0790 0F00 0300 0185
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:52 | INFO     | 17230 | message       | peer 10.16.0.132 ASN 2611    >> EOR(s)
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0017 02
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED 0000 0000
Mon, 30 Sep 2013 11:13:52 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << UPDATE
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 89 second(s) left
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Sending Timer 28 second(s) left
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 001E 02
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED 0000 0007 900F 0003 0001 85
Mon, 30 Sep 2013 11:13:52 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << UPDATE
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 89 second(s) left
Mon, 30 Sep 2013 11:13:52 | INFO     | 17230 | routes        | peer 10.16.0.132 ASN 2611    eor 1/133 (ipv4 flow)
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Sending Timer 28 second(s) left
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0017 03
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing 10.4.2.90 / 10.16.0.132          RECEIVED 0303 0100
Mon, 30 Sep 2013 11:13:52 | INFO     | 17230 | message       | Peer     10.16.0.132 ASN 2611    << NOTIFICATION
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Receive Timer 89 second(s) left
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | timers        | session 1 outgoing peer 10.16.0.132 ASN 2611    Sending Timer 28 second(s) left
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | network       | out loop immediate callback, state is established
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing, closing connection from 10.4.2.90 to 10.16.0.132
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing 10.16.0.132 lost TCP session with peer
Mon, 30 Sep 2013 11:13:52 | INFO     | 17230 | network       | Peer     10.16.0.132 ASN 2611    out loop reset closing connection
Mon, 30 Sep 2013 11:13:52 | DEBUG    | 17230 | wire          | session 1 outgoing, closing connection from 10.4.2.90 to 10.16.0.132
Mon, 30 Sep 2013 11:13:53 | DEBUG    | 17230 | network       | out loop, skipping, not time yet
Mon, 30 Sep 2013 11:13:53 | INFO     | 17230 | reactor       | ^C received
Mon, 30 Sep 2013 11:13:53 | INFO     | 17230 | reactor       | Performing shutdown

Thomas Mangin

unread,

Sep 30, 2013, 7:44:29 AM9/30/13

to exabgp...@googlegroups.com

Hello,

I done a change on git, can you tell me if it makes any difference.
If it doesn't could you please turn on full debugging on the Juniper and tell me more about what it says.

Thomas

Quentin Loos

unread,

Sep 30, 2013, 7:55:53 AM9/30/13

to exabgp...@googlegroups.com

According to the NOTIFICATION received ..

Border Gateway Protocol - NOTIFICATION Message
Marker: ffffffffffffffffffffffffffffffff
Length: 23
Type: NOTIFICATION Message (3)
Major error Code: UPDATE Message Error (3)
Minor error Code (Update Message): Missing Well-known Attribute (3)
Data: 0100

and the RFCC 4271, the missing attribute is the MED.

I'll test your changes.

Quentin Loos

unread,

Sep 30, 2013, 8:04:57 AM9/30/13

to exabgp...@googlegroups.com

Here the log of the juniper device.

Sep 30 14:18:23 trace_on: Tracing to "/var/log/bgp" started
Sep 30 14:18:23.934746 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_v4_message
Sep 30 14:18:23.936268 task_set_socket: task BGP_65406.10.4.2.90+34518 socket 55
Sep 30 14:18:23.936288 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:18:23.936295
Sep 30 14:18:23.936295 BGP SEND 10.16.0.132+179 -> 10.4.2.90+34518
Sep 30 14:18:23.936302 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 14:18:23.936507 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:18:23.936515
Sep 30 14:18:23.936515 BGP SEND 10.16.0.132+179 -> 10.4.2.90+34518
Sep 30 14:18:23.936522 BGP SEND message type 2 (Update) length 23
Sep 30 14:18:23.936527 BGP SEND End of RIB: AFI 1 SAFI 1
Sep 30 14:18:23.936551 bgp_send: sending 30 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:18:23.936558
Sep 30 14:18:23.936558 BGP SEND 10.16.0.132+179 -> 10.4.2.90+34518
Sep 30 14:18:23.936564 BGP SEND message type 2 (Update) length 30
Sep 30 14:18:23.936568 BGP SEND Update PDU length 30
Sep 30 14:18:23.936575 BGP SEND flags 0x90 code MP_unreach(15): AFI/SAFI 1/133
Sep 30 14:18:23.936580 BGP SEND End of RIB: AFI 1 SAFI 133
Sep 30 14:18:23.936898 task_process_events: recv ready for BGP_65406.10.4.2.90+34518
Sep 30 14:18:23.936906 task_job_create_background: create prio 4 job Read peer for task BGP_65406.10.4.2.90+34518
Sep 30 14:18:23.936924 background dispatch running job Read peer for task BGP_65406.10.4.2.90+34518
Sep 30 14:18:23.936930 bgp_read_v4_message: receiving packet(s) from 10.4.2.90 (External AS 65406)
Sep 30 14:18:23.936940
Sep 30 14:18:23.936940 BGP RECV 10.4.2.90+34518 -> 10.16.0.132+179
Sep 30 14:18:23.936947 BGP RECV message type 2 (Update) length 46
Sep 30 14:18:23.936951 BGP RECV Update PDU length 46
Sep 30 14:18:23.936962 BGP RECV flags 0xc0 code Extended Communities(16): 8006:0:0
Sep 30 14:18:23.936967 BGP RECV flags 0x80 code MP_reach(14): AFI/SAFI 1/133
Sep 30 14:18:23.936973 BGP RECV     nhop (null) len 0
Sep 30 14:18:23.936983 BGP RECV     *,*,proto=1/24
Sep 30 14:18:23.937019 bgp_should_merge_as2_and_as4_path():2033 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): No merge of as-paths required as the peer is 4 byte as capable
Sep 30 14:18:23.937026 bgp_process_aspath_and_aggr_attr():2462 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): bgp_should_merge_as2_and_as4_path() says NO
Sep 30 14:18:23.937034 bgp_process_aspath_and_aggr_attr():2499 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): Merged asp: path_len 0, path_seg_len 0, path2_len 0, path2_seg_len 0, path4_len 0, path4_seg_len 0, path_attr_len 0, path_aggr_len 0, path4_aggr_len 0, path4_flags 0x0, path_flags 0x0
Sep 30 14:18:23.937060 bgp_read_v4_update:9220: NOTIFICATION sent to 10.4.2.90 (External AS 65406): code 3 (Update Message Error) subcode 3 (missing well known attribute) value 256
Sep 30 14:18:23.937066 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:18:23.937071
Sep 30 14:18:23.937071 BGP SEND 10.16.0.132+179 -> 10.4.2.90+34518
Sep 30 14:18:23.937078 BGP SEND message type 3 (Notification) length 23
Sep 30 14:18:23.937083 BGP SEND Notification code 3 (Update Message Error) subcode 3 (missing well known attribute)
Sep 30 14:18:23.937088 BGP SEND Data (2 bytes): 01 00
Sep 30 14:18:23.937115 bgp_peer_close: closing peer 10.4.2.90 (External AS 65406), state is 7 (Established)
Sep 30 14:18:23.937121 task_job_delete: delete background job Read peer for task BGP_65406.10.4.2.90+34518
Sep 30 14:18:23.937135 bgp_event: peer 10.4.2.90 (External AS 65406) old state Established event RecvUpdate new state Idle
Sep 30 14:18:23.937175 bgp_close_socket: peer 10.4.2.90 (External AS 65406)
Sep 30 14:18:23.937182 task_close: close socket 55 task BGP_65406.10.4.2.90+34518
Sep 30 14:18:23.937188 task_reset_socket: task BGP_65406.10.4.2.90+34518 socket 55
Sep 30 14:18:23.937209 task_timer_delete: BGP_65406.10.4.2.90_Traffic <Touched>
Sep 30 14:18:23.937228 task_timer_delete: BGP_65406.10.4.2.90_Hold <Touched>
Sep 30 14:18:23.937243 background dispatch completed job Read peer for task BGP_65406.10.4.2.90
Sep 30 14:18:23.937309 bgp_event: peer 10.4.2.90 (External AS 65406) old state Idle event Start new state Active
Sep 30 14:18:23.937318 task_timer_ucreate: created timer BGP_65406.10.4.2.90_Connect  flags <>
Sep 30 14:18:23.937326 task_timer_uset: timer BGP_65406.10.4.2.90_Connect <Touched> set to offset 32 at 14:18:55

Is that the output you want ?

Thomas Mangin

unread,

Sep 30, 2013, 8:22:36 AM9/30/13

to exabgp...@googlegroups.com

Hello Quentin,

bgp_read_v4_update:9220: NOTIFICATION sent to 10.4.2.90 (External AS 65406): code 3 (Update Message Error) subcode 3 (missing well known attribute) value 256

Error, 3, sucode 3 is missing well-known attribute ... however no attribute has a value of 256 !

So The JunOS logs are as claar as mud .. Like me they must use some attribute values internally.

BGP SEND Data (2 bytes): 01 00

This is what I was hoping to get information for .. but 0x0100 is 256 ..

*,*,proto=1/24

Prot 1 is ICMP .. I decoded the same so something must be very wrong somewhere. Please give me a few hours to look into it.

./sbin/exabgp etc/exabgp/ebgp4-juniper-flow.txt --decode "FFFF FFFF FFFF FFFF FFFF FFFF FFFF FFFF 0023 0200 0000 0C80 0E09 0001 8500 0003 0381 01"

decoded update announce flow protocol =ICMP next-hop 0.0.0.0

But your rule does not use ICMP, so I wil try to figure out what is (very) wrong ... Looking into it, I will try to have it fixed today but I have another bug report on my hands too :-(

Thomas

Quentin Loos

unread,

Sep 30, 2013, 8:39:56 AM9/30/13

to exabgp...@googlegroups.com

Arf sorry ! I modified my configuration file for testing ! Here the log for the configuration file posted above.

Sep 30 14:48:22.506494 task_timer_delete: BGP_65406.10.4.2.90_Connect <Touched>
Sep 30 14:48:22.506540 task_set_socket: task BGP_65406.10.4.2.90 socket 55
Sep 30 14:48:22.506550 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option RecvBuffer(0) value 16384
Sep 30 14:48:22.506557 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option SendBuffer(1) value 16384
Sep 30 14:48:22.506577 task_set_option_internal: task BGP_65406.10.4.2.90+34659 socket 55 option PathMTUDiscovery(26) value 0
Sep 30 14:48:22.506588 task_timer_ucreate: created timer BGP_65406.10.4.2.90+34659_Hold  flags <>
Sep 30 14:48:22.506598 task_timer_uset: timer BGP_65406.10.4.2.90+34659_Hold <Touched> set to offset 1:30 at 14:49:52
Sep 30 14:48:22.506605 bgp_event: peer 10.4.2.90 (External AS 65406) old state Active event Open new state OpenSent
Sep 30 14:48:22.506613 advertising receiving-speaker only capabilty to neighbor 10.4.2.90 (External AS 65406)
Sep 30 14:48:22.506637 bgp_4byte_aspath_add_cap():155 AS4-Peer 10.4.2.90 (External AS 65406)(SEND): 4 byte AS capability added, AS 2611
Sep 30 14:48:22.506643 bgp_send: sending 67 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:22.506649
Sep 30 14:48:22.506649 BGP SEND 10.16.0.132+179 -> 10.4.2.90+34659
Sep 30 14:48:22.506656 BGP SEND message type 1 (Open) length 67
Sep 30 14:48:22.506662 BGP SEND version 4 as 2611 holdtime 90 id 10.0.0.122 parmlen 38
Sep 30 14:48:22.506668 BGP SEND MP capability AFI=1, SAFI=1
Sep 30 14:48:22.506672 BGP SEND MP capability AFI=1, SAFI=133
Sep 30 14:48:22.506676 BGP SEND Refresh capability, code=128
Sep 30 14:48:22.506679 BGP SEND Refresh capability, code=2
Sep 30 14:48:22.506684 BGP SEND Restart capability, code=64, time=120, flags=
Sep 30 14:48:22.506689 BGP SEND 4 Byte AS-Path capability (65), as_num 2611
Sep 30 14:48:22.506709 bgp_process_4byte_aspath_cap():288 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): 4 byte AS Cap value stored 65406
 
Sep 30 14:48:22.506730 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenSent event RecvOpen new state OpenConfirm
Sep 30 14:48:22.506735 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:22.506740
Sep 30 14:48:22.506740 BGP SEND 10.16.0.132+179 -> 10.4.2.90+34659
Sep 30 14:48:22.506746 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 14:48:22.506755 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_open
Sep 30 14:48:22.506760 task_set_socket: task BGP_65406.10.4.2.90+34659 socket 55
Sep 30 14:48:23.507092 task_process_events: recv ready for BGP_65406.10.4.2.90+34659
Sep 30 14:48:23.507118 bgp_recv_open: called for peer 10.4.2.90 (External AS 65406)
Sep 30 14:48:23.507128
Sep 30 14:48:23.507128 BGP RECV 10.4.2.90+34659 -> 10.16.0.132+179
Sep 30 14:48:23.507136 BGP RECV message type 4 (KeepAlive) length 19
Sep 30 14:48:23.507143 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenConfirm event RecvKeepAlive new state Established
Sep 30 14:48:23.507229 task_timer_reset: reset BGP_65406.10.4.2.90+34659_Hold
Sep 30 14:48:23.507238 task_timer_set_oneshot_latest: timer BGP_65406.10.4.2.90+34659_Hold interval set to 1:30
Sep 30 14:48:23.507245 task_timer_ucreate: created timer BGP_65406.10.4.2.90+34659_Traffic  flags <>
Sep 30 14:48:23.507254 task_timer_uset: timer BGP_65406.10.4.2.90+34659_Traffic <Touched> set to offset 30 jitter 20 at 14:48:51
Sep 30 14:48:23.507287 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_v4_message
Sep 30 14:48:23.507293 task_set_socket: task BGP_65406.10.4.2.90+34659 socket 55
Sep 30 14:48:23.507311 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:23.507318
Sep 30 14:48:23.507318 BGP SEND 10.16.0.132+179 -> 10.4.2.90+34659
Sep 30 14:48:23.507324 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 14:48:23.507455 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:23.507462
Sep 30 14:48:23.507462 BGP SEND 10.16.0.132+179 -> 10.4.2.90+34659
Sep 30 14:48:23.507469 BGP SEND message type 2 (Update) length 23
Sep 30 14:48:23.507474 BGP SEND End of RIB: AFI 1 SAFI 1
Sep 30 14:48:23.507503 bgp_send: sending 30 bytes to 10.4.2.90 (External AS 65406)


Sep 30 14:48:23.507509

Sep 30 14:48:23.507509 BGP SEND 10.16.0.132+179 -> 10.4.2.90+34659
Sep 30 14:48:23.507516 BGP SEND message type 2 (Update) length 30
Sep 30 14:48:23.507520 BGP SEND Update PDU length 30
Sep 30 14:48:23.507527 BGP SEND flags 0x90 code MP_unreach(15): AFI/SAFI 1/133
Sep 30 14:48:23.507532 BGP SEND End of RIB: AFI 1 SAFI 133
Sep 30 14:48:23.507952 task_process_events: recv ready for BGP_65406.10.4.2.90+34659
Sep 30 14:48:23.507962 task_job_create_background: create prio 4 job Read peer for task BGP_65406.10.4.2.90+34659
Sep 30 14:48:23.507979 background dispatch running job Read peer for task BGP_65406.10.4.2.90+34659
Sep 30 14:48:23.507985 bgp_read_v4_message: receiving packet(s) from 10.4.2.90 (External AS 65406)
Sep 30 14:48:23.507995
Sep 30 14:48:23.507995 BGP RECV 10.4.2.90+34659 -> 10.16.0.132+179
Sep 30 14:48:23.508007 BGP RECV message type 2 (Update) length 49
Sep 30 14:48:23.508011 BGP RECV Update PDU length 49
Sep 30 14:48:23.508022 BGP RECV flags 0xc0 code Extended Communities(16): 8006:0:0
Sep 30 14:48:23.508028 BGP RECV flags 0x80 code MP_reach(14): AFI/SAFI 1/133
Sep 30 14:48:23.508034 BGP RECV     nhop (null) len 0
Sep 30 14:48:23.508043 BGP RECV     *,10.0.0.5/48
Sep 30 14:48:23.508066 bgp_should_merge_as2_and_as4_path():2033 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): No merge of as-paths required as the peer is 4 byte as capable
Sep 30 14:48:23.508073 bgp_process_aspath_and_aggr_attr():2462 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): bgp_should_merge_as2_and_as4_path() says NO
Sep 30 14:48:23.508081 bgp_process_aspath_and_aggr_attr():2499 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): Merged asp: path_len 0, path_seg_len 0, path2_len 0, path2_seg_len 0, path4_len 0, path4_seg_len 0, path_attr_len 0, path_aggr_len 0, path4_aggr_len 0, path4_flags 0x0, path_flags 0x0
Sep 30 14:48:23.508117 bgp_read_v4_update:9220: NOTIFICATION sent to 10.4.2.90 (External AS 65406): code 3 (Update Message Error) subcode 3 (missing well known attribute) value 256
Sep 30 14:48:23.508123 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:23.508128
Sep 30 14:48:23.508128 BGP SEND 10.16.0.132+179 -> 10.4.2.90+34659
Sep 30 14:48:23.508134 BGP SEND message type 3 (Notification) length 23
Sep 30 14:48:23.508139 BGP SEND Notification code 3 (Update Message Error) subcode 3 (missing well known attribute)
Sep 30 14:48:23.508144 BGP SEND Data (2 bytes): 01 00
Sep 30 14:48:23.508172 bgp_peer_close: closing peer 10.4.2.90 (External AS 65406), state is 7 (Established)
Sep 30 14:48:23.508178 task_job_delete: delete background job Read peer for task BGP_65406.10.4.2.90+34659
Sep 30 14:48:23.508192 bgp_event: peer 10.4.2.90 (External AS 65406) old state Established event RecvUpdate new state Idle
Sep 30 14:48:23.508231 bgp_close_socket: peer 10.4.2.90 (External AS 65406)
Sep 30 14:48:23.508242 task_close: close socket 55 task BGP_65406.10.4.2.90+34659
Sep 30 14:48:23.508248 task_reset_socket: task BGP_65406.10.4.2.90+34659 socket 55
Sep 30 14:48:23.508271 task_timer_delete: BGP_65406.10.4.2.90_Traffic <Touched>
Sep 30 14:48:23.508291 task_timer_delete: BGP_65406.10.4.2.90_Hold <Touched>
Sep 30 14:48:23.508306 background dispatch completed job Read peer for task BGP_65406.10.4.2.90
Sep 30 14:48:23.508370 bgp_event: peer 10.4.2.90 (External AS 65406) old state Idle event Start new state Active
Sep 30 14:48:23.508379 task_timer_ucreate: created timer BGP_65406.10.4.2.90_Connect  flags <>
Sep 30 14:48:23.508387 task_timer_uset: timer BGP_65406.10.4.2.90_Connect <Touched> set to offset 32 at 14:48:55
Sep 30 14:48:25.510057 task_timer_delete: BGP_65406.10.4.2.90_Connect <Touched>
Sep 30 14:48:25.510096 task_set_socket: task BGP_65406.10.4.2.90 socket 55
Sep 30 14:48:25.510107 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option RecvBuffer(0) value 16384
Sep 30 14:48:25.510117 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option SendBuffer(1) value 16384
Sep 30 14:48:25.510136 task_set_option_internal: task BGP_65406.10.4.2.90+51182 socket 55 option PathMTUDiscovery(26) value 0
Sep 30 14:48:25.510144 task_timer_ucreate: created timer BGP_65406.10.4.2.90+51182_Hold  flags <>
Sep 30 14:48:25.510153 task_timer_uset: timer BGP_65406.10.4.2.90+51182_Hold <Touched> set to offset 1:30 at 14:49:55
Sep 30 14:48:25.510160 bgp_event: peer 10.4.2.90 (External AS 65406) old state Active event Open new state OpenSent
Sep 30 14:48:25.510167 advertising receiving-speaker only capabilty to neighbor 10.4.2.90 (External AS 65406)
Sep 30 14:48:25.510177 bgp_4byte_aspath_add_cap():155 AS4-Peer 10.4.2.90 (External AS 65406)(SEND): 4 byte AS capability added, AS 2611
Sep 30 14:48:25.510183 bgp_send: sending 67 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:25.510189                  
Sep 30 14:48:25.510189 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51182
Sep 30 14:48:25.510196 BGP SEND message type 1 (Open) length 67
Sep 30 14:48:25.510214 BGP SEND version 4 as 2611 holdtime 90 id 10.0.0.122 parmlen 38
Sep 30 14:48:25.510220 BGP SEND MP capability AFI=1, SAFI=1
Sep 30 14:48:25.510224 BGP SEND MP capability AFI=1, SAFI=133
Sep 30 14:48:25.510228 BGP SEND Refresh capability, code=128
Sep 30 14:48:25.510232 BGP SEND Refresh capability, code=2
Sep 30 14:48:25.510236 BGP SEND Restart capability, code=64, time=120, flags=
Sep 30 14:48:25.510242 BGP SEND 4 Byte AS-Path capability (65), as_num 2611
Sep 30 14:48:25.510260 bgp_process_4byte_aspath_cap():288 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): 4 byte AS Cap value stored 65406
 
Sep 30 14:48:25.510281 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenSent event RecvOpen new state OpenConfirm
Sep 30 14:48:25.510286 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:25.510291
Sep 30 14:48:25.510291 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51182
Sep 30 14:48:25.510298 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 14:48:25.510306 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_open
Sep 30 14:48:25.510312 task_set_socket: task BGP_65406.10.4.2.90+51182 socket 55
Sep 30 14:48:26.510396 task_process_events: recv ready for BGP_65406.10.4.2.90+51182
Sep 30 14:48:26.510423 bgp_recv_open: called for peer 10.4.2.90 (External AS 65406)
Sep 30 14:48:26.510435
Sep 30 14:48:26.510435 BGP RECV 10.4.2.90+51182 -> 10.16.0.132+179
Sep 30 14:48:26.510444 BGP RECV message type 4 (KeepAlive) length 19
Sep 30 14:48:26.510452 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenConfirm event RecvKeepAlive new state Established
Sep 30 14:48:26.510518 task_timer_reset: reset BGP_65406.10.4.2.90+51182_Hold
Sep 30 14:48:26.510528 task_timer_set_oneshot_latest: timer BGP_65406.10.4.2.90+51182_Hold interval set to 1:30
Sep 30 14:48:26.510535 task_timer_ucreate: created timer BGP_65406.10.4.2.90+51182_Traffic  flags <>
Sep 30 14:48:26.510544 task_timer_uset: timer BGP_65406.10.4.2.90+51182_Traffic <Touched> set to offset 30 jitter 20 at 14:48:51
Sep 30 14:48:26.510593 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_v4_message
Sep 30 14:48:26.510600 task_set_socket: task BGP_65406.10.4.2.90+51182 socket 55
Sep 30 14:48:26.510620 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:26.510626
Sep 30 14:48:26.510626 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51182
Sep 30 14:48:26.510633 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 14:48:26.510760 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:26.510766
Sep 30 14:48:26.510766 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51182
Sep 30 14:48:26.510773 BGP SEND message type 2 (Update) length 23
Sep 30 14:48:26.510778 BGP SEND End of RIB: AFI 1 SAFI 1
Sep 30 14:48:26.510807 bgp_send: sending 30 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:26.510813
Sep 30 14:48:26.510813 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51182
Sep 30 14:48:26.510820 BGP SEND message type 2 (Update) length 30
Sep 30 14:48:26.510824 BGP SEND Update PDU length 30
Sep 30 14:48:26.510831 BGP SEND flags 0x90 code MP_unreach(15): AFI/SAFI 1/133
Sep 30 14:48:26.510835 BGP SEND End of RIB: AFI 1 SAFI 133
Sep 30 14:48:26.511122 task_process_events: recv ready for BGP_65406.10.4.2.90+51182
Sep 30 14:48:26.511132 task_job_create_background: create prio 4 job Read peer for task BGP_65406.10.4.2.90+51182
Sep 30 14:48:26.511150 background dispatch running job Read peer for task BGP_65406.10.4.2.90+51182
Sep 30 14:48:26.511156 bgp_read_v4_message: receiving packet(s) from 10.4.2.90 (External AS 65406)
Sep 30 14:48:26.511165
Sep 30 14:48:26.511165 BGP RECV 10.4.2.90+51182 -> 10.16.0.132+179
Sep 30 14:48:26.511172 BGP RECV message type 2 (Update) length 49
Sep 30 14:48:26.511176 BGP RECV Update PDU length 49
Sep 30 14:48:26.511187 BGP RECV flags 0xc0 code Extended Communities(16): 8006:0:0
Sep 30 14:48:26.511192 BGP RECV flags 0x80 code MP_reach(14): AFI/SAFI 1/133
Sep 30 14:48:26.511198 BGP RECV     nhop (null) len 0
Sep 30 14:48:26.511207 BGP RECV     *,10.0.0.5/48
Sep 30 14:48:26.511242 bgp_should_merge_as2_and_as4_path():2033 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): No merge of as-paths required as the peer is 4 byte as capable
Sep 30 14:48:26.511249 bgp_process_aspath_and_aggr_attr():2462 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): bgp_should_merge_as2_and_as4_path() says NO
Sep 30 14:48:26.511257 bgp_process_aspath_and_aggr_attr():2499 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): Merged asp: path_len 0, path_seg_len 0, path2_len 0, path2_seg_len 0, path4_len 0, path4_seg_len 0, path_attr_len 0, path_aggr_len 0, path4_aggr_len 0, path4_flags 0x0, path_flags 0x0
Sep 30 14:48:26.511293 bgp_read_v4_update:9220: NOTIFICATION sent to 10.4.2.90 (External AS 65406): code 3 (Update Message Error) subcode 3 (missing well known attribute) value 256
Sep 30 14:48:26.511299 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:26.511304
Sep 30 14:48:26.511304 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51182
Sep 30 14:48:26.511310 BGP SEND message type 3 (Notification) length 23
Sep 30 14:48:26.511315 BGP SEND Notification code 3 (Update Message Error) subcode 3 (missing well known attribute)
Sep 30 14:48:26.511320 BGP SEND Data (2 bytes): 01 00
Sep 30 14:48:26.511347 bgp_peer_close: closing peer 10.4.2.90 (External AS 65406), state is 7 (Established)
Sep 30 14:48:26.511353 task_job_delete: delete background job Read peer for task BGP_65406.10.4.2.90+51182
Sep 30 14:48:26.511366 bgp_event: peer 10.4.2.90 (External AS 65406) old state Established event RecvUpdate new state Idle
Sep 30 14:48:26.511403 bgp_close_socket: peer 10.4.2.90 (External AS 65406)
Sep 30 14:48:26.511410 task_close: close socket 55 task BGP_65406.10.4.2.90+51182
Sep 30 14:48:26.511415 task_reset_socket: task BGP_65406.10.4.2.90+51182 socket 55
Sep 30 14:48:26.511439 task_timer_delete: BGP_65406.10.4.2.90_Traffic <Touched>
Sep 30 14:48:26.511458 task_timer_delete: BGP_65406.10.4.2.90_Hold <Touched>
Sep 30 14:48:26.511473 background dispatch completed job Read peer for task BGP_65406.10.4.2.90
Sep 30 14:48:26.511540 bgp_event: peer 10.4.2.90 (External AS 65406) old state Idle event Start new state Active
Sep 30 14:48:26.511549 task_timer_ucreate: created timer BGP_65406.10.4.2.90_Connect  flags <>
Sep 30 14:48:26.511557 task_timer_uset: timer BGP_65406.10.4.2.90_Connect <Touched> set to offset 32 at 14:48:58
Sep 30 14:48:28.513155 task_timer_delete: BGP_65406.10.4.2.90_Connect <Touched>
Sep 30 14:48:28.513198 task_set_socket: task BGP_65406.10.4.2.90 socket 55
Sep 30 14:48:28.513208 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option RecvBuffer(0) value 16384
Sep 30 14:48:28.513216 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option SendBuffer(1) value 16384
Sep 30 14:48:28.513235 task_set_option_internal: task BGP_65406.10.4.2.90+41378 socket 55 option PathMTUDiscovery(26) value 0
Sep 30 14:48:28.513243 task_timer_ucreate: created timer BGP_65406.10.4.2.90+41378_Hold  flags <>
Sep 30 14:48:28.513252 task_timer_uset: timer BGP_65406.10.4.2.90+41378_Hold <Touched> set to offset 1:30 at 14:49:58
Sep 30 14:48:28.513259 bgp_event: peer 10.4.2.90 (External AS 65406) old state Active event Open new state OpenSent
Sep 30 14:48:28.513266 advertising receiving-speaker only capabilty to neighbor 10.4.2.90 (External AS 65406)
Sep 30 14:48:28.513277 bgp_4byte_aspath_add_cap():155 AS4-Peer 10.4.2.90 (External AS 65406)(SEND): 4 byte AS capability added, AS 2611
Sep 30 14:48:28.513282 bgp_send: sending 67 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:28.513288
Sep 30 14:48:28.513288 BGP SEND 10.16.0.132+179 -> 10.4.2.90+41378
Sep 30 14:48:28.513295 BGP SEND message type 1 (Open) length 67
Sep 30 14:48:28.513301 BGP SEND version 4 as 2611 holdtime 90 id 10.0.0.122 parmlen 38
Sep 30 14:48:28.513306 BGP SEND MP capability AFI=1, SAFI=1
Sep 30 14:48:28.513311 BGP SEND MP capability AFI=1, SAFI=133
Sep 30 14:48:28.513315 BGP SEND Refresh capability, code=128
Sep 30 14:48:28.513318 BGP SEND Refresh capability, code=2
Sep 30 14:48:28.513323 BGP SEND Restart capability, code=64, time=120, flags=
Sep 30 14:48:28.513339 BGP SEND 4 Byte AS-Path capability (65), as_num 2611
Sep 30 14:48:28.513356 bgp_process_4byte_aspath_cap():288 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): 4 byte AS Cap value stored 65406
                                        
Sep 30 14:48:28.513377 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenSent event RecvOpen new state OpenConfirm
Sep 30 14:48:28.513382 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:28.513387
Sep 30 14:48:28.513387 BGP SEND 10.16.0.132+179 -> 10.4.2.90+41378
Sep 30 14:48:28.513393 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 14:48:28.513401 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_open
Sep 30 14:48:28.513407 task_set_socket: task BGP_65406.10.4.2.90+41378 socket 55
Sep 30 14:48:29.513682 task_process_events: recv ready for BGP_65406.10.4.2.90+41378
Sep 30 14:48:29.513713 bgp_recv_open: called for peer 10.4.2.90 (External AS 65406)
Sep 30 14:48:29.513724
Sep 30 14:48:29.513724 BGP RECV 10.4.2.90+41378 -> 10.16.0.132+179
Sep 30 14:48:29.513732 BGP RECV message type 4 (KeepAlive) length 19
Sep 30 14:48:29.513740 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenConfirm event RecvKeepAlive new state Established
Sep 30 14:48:29.513813 task_timer_reset: reset BGP_65406.10.4.2.90+41378_Hold
Sep 30 14:48:29.513823 task_timer_set_oneshot_latest: timer BGP_65406.10.4.2.90+41378_Hold interval set to 1:30
Sep 30 14:48:29.513829 task_timer_ucreate: created timer BGP_65406.10.4.2.90+41378_Traffic  flags <>
Sep 30 14:48:29.513838 task_timer_uset: timer BGP_65406.10.4.2.90+41378_Traffic <Touched> set to offset 30 jitter 20 at 14:48:54
Sep 30 14:48:29.513871 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_v4_message
Sep 30 14:48:29.513878 task_set_socket: task BGP_65406.10.4.2.90+41378 socket 55
Sep 30 14:48:29.513896 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:29.513903
Sep 30 14:48:29.513903 BGP SEND 10.16.0.132+179 -> 10.4.2.90+41378
Sep 30 14:48:29.513922 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 14:48:29.514050 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:29.514057
Sep 30 14:48:29.514057 BGP SEND 10.16.0.132+179 -> 10.4.2.90+41378
Sep 30 14:48:29.514063 BGP SEND message type 2 (Update) length 23
Sep 30 14:48:29.514069 BGP SEND End of RIB: AFI 1 SAFI 1
Sep 30 14:48:29.514094 bgp_send: sending 30 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:29.514100
Sep 30 14:48:29.514100 BGP SEND 10.16.0.132+179 -> 10.4.2.90+41378
Sep 30 14:48:29.514107 BGP SEND message type 2 (Update) length 30
Sep 30 14:48:29.514111 BGP SEND Update PDU length 30
Sep 30 14:48:29.514118 BGP SEND flags 0x90 code MP_unreach(15): AFI/SAFI 1/133
Sep 30 14:48:29.514123 BGP SEND End of RIB: AFI 1 SAFI 133
Sep 30 14:48:29.514468 task_process_events: recv ready for BGP_65406.10.4.2.90+41378
Sep 30 14:48:29.514477 task_job_create_background: create prio 4 job Read peer for task BGP_65406.10.4.2.90+41378
Sep 30 14:48:29.514498 background dispatch running job Read peer for task BGP_65406.10.4.2.90+41378
Sep 30 14:48:29.514504 bgp_read_v4_message: receiving packet(s) from 10.4.2.90 (External AS 65406)
Sep 30 14:48:29.514513
Sep 30 14:48:29.514513 BGP RECV 10.4.2.90+41378 -> 10.16.0.132+179
Sep 30 14:48:29.514521 BGP RECV message type 2 (Update) length 49
Sep 30 14:48:29.514525 BGP RECV Update PDU length 49
Sep 30 14:48:29.514536 BGP RECV flags 0xc0 code Extended Communities(16): 8006:0:0
Sep 30 14:48:29.514541 BGP RECV flags 0x80 code MP_reach(14): AFI/SAFI 1/133
Sep 30 14:48:29.514548 BGP RECV     nhop (null) len 0
Sep 30 14:48:29.514557 BGP RECV     *,10.0.0.5/48
Sep 30 14:48:29.514579 bgp_should_merge_as2_and_as4_path():2033 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): No merge of as-paths required as the peer is 4 byte as capable
Sep 30 14:48:29.514586 bgp_process_aspath_and_aggr_attr():2462 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): bgp_should_merge_as2_and_as4_path() says NO
Sep 30 14:48:29.514594 bgp_process_aspath_and_aggr_attr():2499 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): Merged asp: path_len 0, path_seg_len 0, path2_len 0, path2_seg_len 0, path4_len 0, path4_seg_len 0, path_attr_len 0, path_aggr_len 0, path4_aggr_len 0, path4_flags 0x0, path_flags 0x0
Sep 30 14:48:29.514639 bgp_read_v4_update:9220: NOTIFICATION sent to 10.4.2.90 (External AS 65406): code 3 (Update Message Error) subcode 3 (missing well known attribute) value 256
Sep 30 14:48:29.514646 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:29.514650
Sep 30 14:48:29.514650 BGP SEND 10.16.0.132+179 -> 10.4.2.90+41378
Sep 30 14:48:29.514657 BGP SEND message type 3 (Notification) length 23
Sep 30 14:48:29.514662 BGP SEND Notification code 3 (Update Message Error) subcode 3 (missing well known attribute)
Sep 30 14:48:29.514667 BGP SEND Data (2 bytes): 01 00
Sep 30 14:48:29.514694 bgp_peer_close: closing peer 10.4.2.90 (External AS 65406), state is 7 (Established)
Sep 30 14:48:29.514701 task_job_delete: delete background job Read peer for task BGP_65406.10.4.2.90+41378
Sep 30 14:48:29.514714 bgp_event: peer 10.4.2.90 (External AS 65406) old state Established event RecvUpdate new state Idle
Sep 30 14:48:29.514753 bgp_close_socket: peer 10.4.2.90 (External AS 65406)
Sep 30 14:48:29.514760 task_close: close socket 55 task BGP_65406.10.4.2.90+41378
Sep 30 14:48:29.514765 task_reset_socket: task BGP_65406.10.4.2.90+41378 socket 55
Sep 30 14:48:29.514790 task_timer_delete: BGP_65406.10.4.2.90_Traffic <Touched>
Sep 30 14:48:29.514810 task_timer_delete: BGP_65406.10.4.2.90_Hold <Touched>
Sep 30 14:48:29.514825 background dispatch completed job Read peer for task BGP_65406.10.4.2.90
Sep 30 14:48:29.514888 bgp_event: peer 10.4.2.90 (External AS 65406) old state Idle event Start new state Active
Sep 30 14:48:29.514896 task_timer_ucreate: created timer BGP_65406.10.4.2.90_Connect  flags <>
Sep 30 14:48:29.515010 task_timer_uset: timer BGP_65406.10.4.2.90_Connect <Touched> set to offset 32 at 14:49:01
Sep 30 14:48:32.517118 task_timer_delete: BGP_65406.10.4.2.90_Connect <Touched>
Sep 30 14:48:32.517169 task_set_socket: task BGP_65406.10.4.2.90 socket 55
Sep 30 14:48:32.517180 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option RecvBuffer(0) value 16384
Sep 30 14:48:32.517187 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option SendBuffer(1) value 16384
Sep 30 14:48:32.517207 task_set_option_internal: task BGP_65406.10.4.2.90+51688 socket 55 option PathMTUDiscovery(26) value 0
Sep 30 14:48:32.517215 task_timer_ucreate: created timer BGP_65406.10.4.2.90+51688_Hold  flags <>
Sep 30 14:48:32.517224 task_timer_uset: timer BGP_65406.10.4.2.90+51688_Hold <Touched> set to offset 1:30 at 14:50:02
Sep 30 14:48:32.517231 bgp_event: peer 10.4.2.90 (External AS 65406) old state Active event Open new state OpenSent
Sep 30 14:48:32.517239 advertising receiving-speaker only capabilty to neighbor 10.4.2.90 (External AS 65406)
Sep 30 14:48:32.517249 bgp_4byte_aspath_add_cap():155 AS4-Peer 10.4.2.90 (External AS 65406)(SEND): 4 byte AS capability added, AS 2611
Sep 30 14:48:32.517255 bgp_send: sending 67 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:32.517260
Sep 30 14:48:32.517260 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51688
Sep 30 14:48:32.517267 BGP SEND message type 1 (Open) length 67
Sep 30 14:48:32.517274 BGP SEND version 4 as 2611 holdtime 90 id 10.0.0.122 parmlen 38
Sep 30 14:48:32.517279 BGP SEND MP capability AFI=1, SAFI=1
Sep 30 14:48:32.517283 BGP SEND MP capability AFI=1, SAFI=133
Sep 30 14:48:32.517287 BGP SEND Refresh capability, code=128
Sep 30 14:48:32.517291 BGP SEND Refresh capability, code=2
Sep 30 14:48:32.517296 BGP SEND Restart capability, code=64, time=120, flags=
Sep 30 14:48:32.517301 BGP SEND 4 Byte AS-Path capability (65), as_num 2611
Sep 30 14:48:32.517320 bgp_process_4byte_aspath_cap():288 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): 4 byte AS Cap value stored 65406
 
Sep 30 14:48:32.517341 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenSent event RecvOpen new state OpenConfirm
Sep 30 14:48:32.517347 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:32.517356
Sep 30 14:48:32.517356 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51688
Sep 30 14:48:32.517363 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 14:48:32.517371 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_open
Sep 30 14:48:32.517377 task_set_socket: task BGP_65406.10.4.2.90+51688 socket 55
Sep 30 14:48:33.516641 task_process_events: recv ready for BGP_65406.10.4.2.90+51688
Sep 30 14:48:33.516669 bgp_recv_open: called for peer 10.4.2.90 (External AS 65406)
Sep 30 14:48:33.516679
Sep 30 14:48:33.516679 BGP RECV 10.4.2.90+51688 -> 10.16.0.132+179
Sep 30 14:48:33.516687 BGP RECV message type 4 (KeepAlive) length 19
Sep 30 14:48:33.516695 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenConfirm event RecvKeepAlive new state Established
Sep 30 14:48:33.516771 task_timer_reset: reset BGP_65406.10.4.2.90+51688_Hold
Sep 30 14:48:33.516782 task_timer_set_oneshot_latest: timer BGP_65406.10.4.2.90+51688_Hold interval set to 1:30
Sep 30 14:48:33.516789 task_timer_ucreate: created timer BGP_65406.10.4.2.90+51688_Traffic  flags <>
Sep 30 14:48:33.516799 task_timer_uset: timer BGP_65406.10.4.2.90+51688_Traffic <Touched> set to offset 30 jitter 20 at 14:49:02
Sep 30 14:48:33.516831 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_v4_message
Sep 30 14:48:33.516838 task_set_socket: task BGP_65406.10.4.2.90+51688 socket 55
Sep 30 14:48:33.516857 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:33.517070
Sep 30 14:48:33.517070 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51688
Sep 30 14:48:33.517079 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 14:48:33.517204 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:33.517211
Sep 30 14:48:33.517211 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51688
Sep 30 14:48:33.517218 BGP SEND message type 2 (Update) length 23
Sep 30 14:48:33.517224 BGP SEND End of RIB: AFI 1 SAFI 1
Sep 30 14:48:33.517252 bgp_send: sending 30 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:33.517277
Sep 30 14:48:33.517277 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51688
Sep 30 14:48:33.517285 BGP SEND message type 2 (Update) length 30
Sep 30 14:48:33.517289 BGP SEND Update PDU length 30
Sep 30 14:48:33.517296 BGP SEND flags 0x90 code MP_unreach(15): AFI/SAFI 1/133
Sep 30 14:48:33.517300 BGP SEND End of RIB: AFI 1 SAFI 133
Sep 30 14:48:33.517568 task_process_events: recv ready for BGP_65406.10.4.2.90+51688
Sep 30 14:48:33.517577 task_job_create_background: create prio 4 job Read peer for task BGP_65406.10.4.2.90+51688
Sep 30 14:48:33.517595 background dispatch running job Read peer for task BGP_65406.10.4.2.90+51688
Sep 30 14:48:33.517600 bgp_read_v4_message: receiving packet(s) from 10.4.2.90 (External AS 65406)
Sep 30 14:48:33.517609
Sep 30 14:48:33.517609 BGP RECV 10.4.2.90+51688 -> 10.16.0.132+179
Sep 30 14:48:33.517617 BGP RECV message type 2 (Update) length 49
Sep 30 14:48:33.517621 BGP RECV Update PDU length 49
Sep 30 14:48:33.517631 BGP RECV flags 0xc0 code Extended Communities(16): 8006:0:0
Sep 30 14:48:33.517637 BGP RECV flags 0x80 code MP_reach(14): AFI/SAFI 1/133
Sep 30 14:48:33.517643 BGP RECV     nhop (null) len 0
Sep 30 14:48:33.517652 BGP RECV     *,10.0.0.5/48
Sep 30 14:48:33.517675 bgp_should_merge_as2_and_as4_path():2033 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): No merge of as-paths required as the peer is 4 byte as capable
Sep 30 14:48:33.517682 bgp_process_aspath_and_aggr_attr():2462 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): bgp_should_merge_as2_and_as4_path() says NO
Sep 30 14:48:33.517689 bgp_process_aspath_and_aggr_attr():2499 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): Merged asp: path_len 0, path_seg_len 0, path2_len 0, path2_seg_len 0, path4_len 0, path4_seg_len 0, path_attr_len 0, path_aggr_len 0, path4_aggr_len 0, path4_flags 0x0, path_flags 0x0
Sep 30 14:48:33.517725 bgp_read_v4_update:9220: NOTIFICATION sent to 10.4.2.90 (External AS 65406): code 3 (Update Message Error) subcode 3 (missing well known attribute) value 256
Sep 30 14:48:33.517744 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:33.517749
Sep 30 14:48:33.517749 BGP SEND 10.16.0.132+179 -> 10.4.2.90+51688
Sep 30 14:48:33.517755 BGP SEND message type 3 (Notification) length 23
Sep 30 14:48:33.517760 BGP SEND Notification code 3 (Update Message Error) subcode 3 (missing well known attribute)
Sep 30 14:48:33.517765 BGP SEND Data (2 bytes): 01 00
Sep 30 14:48:33.517793 bgp_peer_close: closing peer 10.4.2.90 (External AS 65406), state is 7 (Established)
Sep 30 14:48:33.517799 task_job_delete: delete background job Read peer for task BGP_65406.10.4.2.90+51688
Sep 30 14:48:33.517812 bgp_event: peer 10.4.2.90 (External AS 65406) old state Established event RecvUpdate new state Idle
Sep 30 14:48:33.517852 bgp_close_socket: peer 10.4.2.90 (External AS 65406)
Sep 30 14:48:33.517858 task_close: close socket 55 task BGP_65406.10.4.2.90+51688
Sep 30 14:48:33.517867 task_reset_socket: task BGP_65406.10.4.2.90+51688 socket 55
Sep 30 14:48:33.517891 task_timer_delete: BGP_65406.10.4.2.90_Traffic <Touched>
Sep 30 14:48:33.517910 task_timer_delete: BGP_65406.10.4.2.90_Hold <Touched>
Sep 30 14:48:33.517924 background dispatch completed job Read peer for task BGP_65406.10.4.2.90
Sep 30 14:48:33.517985 bgp_event: peer 10.4.2.90 (External AS 65406) old state Idle event Start new state Active
Sep 30 14:48:33.517993 task_timer_ucreate: created timer BGP_65406.10.4.2.90_Connect  flags <>
Sep 30 14:48:33.518001 task_timer_uset: timer BGP_65406.10.4.2.90_Connect <Touched> set to offset 32 at 14:49:05
Sep 30 14:48:37.521779 task_timer_delete: BGP_65406.10.4.2.90_Connect <Touched>
Sep 30 14:48:37.521820 task_set_socket: task BGP_65406.10.4.2.90 socket 55
Sep 30 14:48:37.521831 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option RecvBuffer(0) value 16384
Sep 30 14:48:37.521838 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option SendBuffer(1) value 16384
Sep 30 14:48:37.521858 task_set_option_internal: task BGP_65406.10.4.2.90+54002 socket 55 option PathMTUDiscovery(26) value 0
Sep 30 14:48:37.521878 task_timer_ucreate: created timer BGP_65406.10.4.2.90+54002_Hold  flags <>
Sep 30 14:48:37.521888 task_timer_uset: timer BGP_65406.10.4.2.90+54002_Hold <Touched> set to offset 1:30 at 14:50:07
Sep 30 14:48:37.521895 bgp_event: peer 10.4.2.90 (External AS 65406) old state Active event Open new state OpenSent
Sep 30 14:48:37.521902 advertising receiving-speaker only capabilty to neighbor 10.4.2.90 (External AS 65406)
Sep 30 14:48:37.521912 bgp_4byte_aspath_add_cap():155 AS4-Peer 10.4.2.90 (External AS 65406)(SEND): 4 byte AS capability added, AS 2611
Sep 30 14:48:37.521918 bgp_send: sending 67 bytes to 10.4.2.90 (External AS 65406)
Sep 30 14:48:37.521924
Sep 30 14:48:37.521924 BGP SEND 10.16.0.132+179 -> 10.4.2.90+54002
Sep 30 14:48:37.521931 BGP SEND message type 1 (Open) length 67
Sep 30 14:48:37.521937 BGP SEND version 4 as 2611 holdtime 90 id 10.0.0.122 parmlen 38
Sep 30 14:48:37.521943 BGP SEND MP capability AFI=1, SAFI=1
Sep 30 14:48:37.521947 BGP SEND MP capability AFI=1, SAFI=133
Sep 30 14:48:37.521951 BGP SEND Refresh capability, code=128
Sep 30 14:48:37.521954 BGP SEND Refresh capability, code=2
Sep 30 14:48:37.521959 BGP SEND Restart capability, code=64, time=120, flags=
Sep 30 14:48:37.521964 BGP SEND 4 Byte AS-Path capability (65), as_num 2611
Sep 30 14:48:37.521981 bgp_process_4byte_aspath_cap():288 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): 4 byte AS Cap value stored 65406

Thomas Mangin

unread,

Sep 30, 2013, 8:48:01 AM9/30/13

to exabgp...@googlegroups.com

On 30 Sep 2013, at 13:39, Quentin Loos <quenti...@gmail.com> wrote:

Arf sorry ! I modified my configuration file for testing ! Here the log for the configuration file posted above.

I currently believe that it could be as in 3.2 I do not include the next-hop attribute which is mandatory.

Let me verify this hypothesis.

Thomas

Thomas Mangin

unread,

Sep 30, 2013, 9:06:18 AM9/30/13

to exabgp...@googlegroups.com

Can you please try again ..

https://github.com/thomas-mangin/exabgp/commit/51b4ff2945cc6bc70032f6a96e7ab3778ca0a9f4

Thomas

--
You received this message because you are subscribed to the Google Groups "exabgp-users" group.
To unsubscribe from this group and stop receiving emails from it, send an email to exabgp-users...@googlegroups.com.
For more options, visit https://groups.google.com/groups/opt_out.

Quentin Loos

unread,

Sep 30, 2013, 9:15:19 AM9/30/13

to exabgp...@googlegroups.com

Nop, same NOTIFICATION message.

Quentin Loos

unread,

Sep 30, 2013, 9:19:55 AM9/30/13

to exabgp...@googlegroups.com

Sep 30 15:33:26.671502 task_process_events: connect ready for BGP_65406.10.4.2.90+179
Sep 30 15:33:26.671550 bgp_connect_complete: error connecting to 10.4.2.90 (External AS 65406): Socket is not connected
Sep 30 15:33:26.671555 bgp_close_socket: peer 10.4.2.90 (External AS 65406)
Sep 30 15:33:26.671561 task_close: close socket 55 task BGP_65406.10.4.2.90+179
Sep 30 15:33:26.671566 task_reset_socket: task BGP_65406.10.4.2.90+179 socket 55
Sep 30 15:33:26.671592 bgp_event: peer 10.4.2.90 (External AS 65406) old state Connect event OpenFail new state Idle
Sep 30 15:33:26.671660 bgp_event: peer 10.4.2.90 (External AS 65406) old state Idle event Start new state Connect
Sep 30 15:33:26.671666 bgp_connect_start: peer 10.4.2.90 (External AS 65406)
Sep 30 15:33:26.671671 bgp_event: peer 10.4.2.90 (External AS 65406) old state Connect event ConnectRetry new state Connect
Sep 30 15:33:26.671689 task_get_socket: domain AF_INET  type SOCK_STREAM  protocol 0  socket 55
Sep 30 15:33:26.671701 task_set_socket: task BGP_65406.10.4.2.90 socket 55
Sep 30 15:33:26.671718 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option NonBlocking(8) value 1
Sep 30 15:33:26.671726 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option ReUsePort(38) value 1
Sep 30 15:33:26.671733 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option PathMTUDiscovery(26) value 0
Sep 30 15:33:26.671741 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option TOS(16) value 192
Sep 30 15:33:26.671758 task_addr_local: task BGP_65406.10.4.2.90 address 10.16.0.132
Sep 30 15:33:26.671790 task_connect: task BGP_65406.10.4.2.90+179 addr 10.4.2.90+179task_timer_reset: reset BGP_65406.10.4.2.90+179_Connect
Sep 30 15:33:26.671812 task_timer_set_oneshot_latest: timer BGP_65406.10.4.2.90+179_Connect interval set to 32
Sep 30 15:33:26.798925 10.4.2.90 (External AS 65406): reseting pending active connection
Sep 30 15:33:26.798964 bgp_peer_close: closing peer 10.4.2.90 (External AS 65406), state is 2 (Connect)
Sep 30 15:33:26.798973 bgp_close_socket: peer 10.4.2.90 (External AS 65406)
Sep 30 15:33:26.798979 task_close: close socket 55 task BGP_65406.10.4.2.90+179
Sep 30 15:33:26.798984 task_reset_socket: task BGP_65406.10.4.2.90+179 socket 55
Sep 30 15:33:26.799004 task_timer_delete: BGP_65406.10.4.2.90_Connect <Touched>
Sep 30 15:33:26.799012 bgp_event: peer 10.4.2.90 (External AS 65406) old state Connect event RecvOpen new state Idle
Sep 30 15:33:26.799060 bgp_event: peer 10.4.2.90 (External AS 65406) old state Idle event Start new state Active
Sep 30 15:33:26.799069 task_timer_ucreate: created timer BGP_65406.10.4.2.90_Connect  flags <>
Sep 30 15:33:26.799079 task_timer_uset: timer BGP_65406.10.4.2.90_Connect <Touched> set to offset 32 at 15:33:58
Sep 30 15:33:26.799085 task_timer_delete: BGP_65406.10.4.2.90_Connect <Touched>
Sep 30 15:33:26.799093 task_set_socket: task BGP_65406.10.4.2.90 socket 62
Sep 30 15:33:26.799103 task_set_option_internal: task BGP_65406.10.4.2.90 socket 62 option RecvBuffer(0) value 16384
Sep 30 15:33:26.799110 task_set_option_internal: task BGP_65406.10.4.2.90 socket 62 option SendBuffer(1) value 16384
Sep 30 15:33:26.799129 task_set_option_internal: task BGP_65406.10.4.2.90+50270 socket 62 option PathMTUDiscovery(26) value 0
Sep 30 15:33:26.799137 task_timer_ucreate: created timer BGP_65406.10.4.2.90+50270_Hold  flags <>
Sep 30 15:33:26.799144 task_timer_uset: timer BGP_65406.10.4.2.90+50270_Hold <Touched> set to offset 1:30 at 15:34:56
Sep 30 15:33:26.799150 bgp_event: peer 10.4.2.90 (External AS 65406) old state Active event Open new state OpenSent
Sep 30 15:33:26.799158 advertising receiving-speaker only capabilty to neighbor 10.4.2.90 (External AS 65406)
Sep 30 15:33:26.799168 bgp_4byte_aspath_add_cap():155 AS4-Peer 10.4.2.90 (External AS 65406)(SEND): 4 byte AS capability added, AS 2611
Sep 30 15:33:26.799174 bgp_send: sending 67 bytes to 10.4.2.90 (External AS 65406)
Sep 30 15:33:26.799186
Sep 30 15:33:26.799186 BGP SEND 10.16.0.132+179 -> 10.4.2.90+50270
Sep 30 15:33:26.799194 BGP SEND message type 1 (Open) length 67
Sep 30 15:33:26.799200 BGP SEND version 4 as 2611 holdtime 90 id 10.0.0.122 parmlen 38
Sep 30 15:33:26.799205 BGP SEND MP capability AFI=1, SAFI=1
Sep 30 15:33:26.799209 BGP SEND MP capability AFI=1, SAFI=133
Sep 30 15:33:26.799213 BGP SEND Refresh capability, code=128
Sep 30 15:33:26.799217 BGP SEND Refresh capability, code=2
Sep 30 15:33:26.799222 BGP SEND Restart capability, code=64, time=120, flags=
Sep 30 15:33:26.799227 BGP SEND 4 Byte AS-Path capability (65), as_num 2611
Sep 30 15:33:26.799244 bgp_process_4byte_aspath_cap():288 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): 4 byte AS Cap value stored 65406
 
Sep 30 15:33:26.799264 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenSent event RecvOpen new state OpenConfirm
Sep 30 15:33:26.799270 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 15:33:26.799274
Sep 30 15:33:26.799274 BGP SEND 10.16.0.132+179 -> 10.4.2.90+50270
Sep 30 15:33:26.799281 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 15:33:26.799289 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_open
Sep 30 15:33:26.799294 task_set_socket: task BGP_65406.10.4.2.90+50270 socket 62
Sep 30 15:33:27.799376 task_process_events: recv ready for BGP_65406.10.4.2.90+50270
Sep 30 15:33:27.799406 bgp_recv_open: called for peer 10.4.2.90 (External AS 65406)
Sep 30 15:33:27.799417
Sep 30 15:33:27.799417 BGP RECV 10.4.2.90+50270 -> 10.16.0.132+179
Sep 30 15:33:27.799425 BGP RECV message type 4 (KeepAlive) length 19
Sep 30 15:33:27.799433 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenConfirm event RecvKeepAlive new state Established
Sep 30 15:33:27.799492 task_timer_reset: reset BGP_65406.10.4.2.90+50270_Hold
Sep 30 15:33:27.799501 task_timer_set_oneshot_latest: timer BGP_65406.10.4.2.90+50270_Hold interval set to 1:30
Sep 30 15:33:27.799508 task_timer_ucreate: created timer BGP_65406.10.4.2.90+50270_Traffic  flags <>
Sep 30 15:33:27.799526 task_timer_uset: timer BGP_65406.10.4.2.90+50270_Traffic <Touched> set to offset 30 jitter 20 at 15:33:53
Sep 30 15:33:27.799559 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_v4_message
Sep 30 15:33:27.799566 task_set_socket: task BGP_65406.10.4.2.90+50270 socket 62
Sep 30 15:33:27.799585 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 15:33:27.799592
Sep 30 15:33:27.799592 BGP SEND 10.16.0.132+179 -> 10.4.2.90+50270
Sep 30 15:33:27.799598 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 15:33:27.799725 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 15:33:27.799732                  
Sep 30 15:33:27.799732 BGP SEND 10.16.0.132+179 -> 10.4.2.90+50270
Sep 30 15:33:27.799739 BGP SEND message type 2 (Update) length 23
Sep 30 15:33:27.799745 BGP SEND End of RIB: AFI 1 SAFI 1
Sep 30 15:33:27.799773 bgp_send: sending 30 bytes to 10.4.2.90 (External AS 65406)
Sep 30 15:33:27.799780
Sep 30 15:33:27.799780 BGP SEND 10.16.0.132+179 -> 10.4.2.90+50270
Sep 30 15:33:27.799786 BGP SEND message type 2 (Update) length 30
Sep 30 15:33:27.799790 BGP SEND Update PDU length 30
Sep 30 15:33:27.799797 BGP SEND flags 0x90 code MP_unreach(15): AFI/SAFI 1/133
Sep 30 15:33:27.799802 BGP SEND End of RIB: AFI 1 SAFI 133
Sep 30 15:33:27.800111 task_process_events: recv ready for BGP_65406.10.4.2.90+50270
Sep 30 15:33:27.800120 task_job_create_background: create prio 4 job Read peer for task BGP_65406.10.4.2.90+50270
Sep 30 15:33:27.800138 background dispatch running job Read peer for task BGP_65406.10.4.2.90+50270
Sep 30 15:33:27.800144 bgp_read_v4_message: receiving packet(s) from 10.4.2.90 (External AS 65406)
Sep 30 15:33:27.800154
Sep 30 15:33:27.800154 BGP RECV 10.4.2.90+50270 -> 10.16.0.132+179
Sep 30 15:33:27.800161 BGP RECV message type 2 (Update) length 56
Sep 30 15:33:27.800165 BGP RECV Update PDU length 56
Sep 30 15:33:27.800170 BGP RECV flags 0x40 code NextHop(3): 0.0.0.0
Sep 30 15:33:27.800182 BGP RECV flags 0xc0 code Extended Communities(16): 8006:0:0
Sep 30 15:33:27.800193 BGP RECV flags 0x80 code MP_reach(14): AFI/SAFI 1/133
Sep 30 15:33:27.800200 BGP RECV     nhop (null) len 0
Sep 30 15:33:27.800209 BGP RECV     *,10.0.0.5/48
Sep 30 15:33:27.800233 bgp_should_merge_as2_and_as4_path():2033 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): No merge of as-paths required as the peer is 4 byte as capable
Sep 30 15:33:27.800240 bgp_process_aspath_and_aggr_attr():2462 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): bgp_should_merge_as2_and_as4_path() says NO
Sep 30 15:33:27.800248 bgp_process_aspath_and_aggr_attr():2499 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): Merged asp: path_len 0, path_seg_len 0, path2_len 0, path2_seg_len 0, path4_len 0, path4_seg_len 0, path_attr_len 0, path_aggr_len 0, path4_aggr_len 0, path4_flags 0x0, path_flags 0x0
Sep 30 15:33:27.800280 bgp_read_v4_update:9220: NOTIFICATION sent to 10.4.2.90 (External AS 65406): code 3 (Update Message Error) subcode 3 (missing well known attribute) value 256
Sep 30 15:33:27.800286 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 15:33:27.800295
Sep 30 15:33:27.800295 BGP SEND 10.16.0.132+179 -> 10.4.2.90+50270
Sep 30 15:33:27.800301 BGP SEND message type 3 (Notification) length 23
Sep 30 15:33:27.800306 BGP SEND Notification code 3 (Update Message Error) subcode 3 (missing well known attribute)
Sep 30 15:33:27.800311 BGP SEND Data (2 bytes): 01 00
Sep 30 15:33:27.800338 bgp_peer_close: closing peer 10.4.2.90 (External AS 65406), state is 7 (Established)
Sep 30 15:33:27.800344 task_job_delete: delete background job Read peer for task BGP_65406.10.4.2.90+50270
Sep 30 15:33:27.800358 bgp_event: peer 10.4.2.90 (External AS 65406) old state Established event RecvUpdate new state Idle
Sep 30 15:33:27.800395 bgp_close_socket: peer 10.4.2.90 (External AS 65406)
Sep 30 15:33:27.800402 task_close: close socket 62 task BGP_65406.10.4.2.90+50270
Sep 30 15:33:27.800407 task_reset_socket: task BGP_65406.10.4.2.90+50270 socket 62
Sep 30 15:33:27.800429 task_timer_delete: BGP_65406.10.4.2.90_Traffic <Touched>
Sep 30 15:33:27.800452 task_timer_delete: BGP_65406.10.4.2.90_Hold <Touched>
Sep 30 15:33:27.800467 background dispatch completed job Read peer for task BGP_65406.10.4.2.90
Sep 30 15:33:27.800530 bgp_event: peer 10.4.2.90 (External AS 65406) old state Idle event Start new state Active
Sep 30 15:33:27.800538 task_timer_ucreate: created timer BGP_65406.10.4.2.90_Connect  flags <>
Sep 30 15:33:27.800547 task_timer_uset: timer BGP_65406.10.4.2.90_Connect <Touched> set to offset 32 at 15:33:59
Sep 30 15:33:29.802381 task_timer_delete: BGP_65406.10.4.2.90_Connect <Touched>
Sep 30 15:33:29.802418 task_set_socket: task BGP_65406.10.4.2.90 socket 55
Sep 30 15:33:29.802428 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option RecvBuffer(0) value 16384
Sep 30 15:33:29.802436 task_set_option_internal: task BGP_65406.10.4.2.90 socket 55 option SendBuffer(1) value 16384
Sep 30 15:33:29.802455 task_set_option_internal: task BGP_65406.10.4.2.90+48191 socket 55 option PathMTUDiscovery(26) value 0
Sep 30 15:33:29.802463 task_timer_ucreate: created timer BGP_65406.10.4.2.90+48191_Hold  flags <>
Sep 30 15:33:29.802472 task_timer_uset: timer BGP_65406.10.4.2.90+48191_Hold <Touched> set to offset 1:30 at 15:34:59
Sep 30 15:33:29.802479 bgp_event: peer 10.4.2.90 (External AS 65406) old state Active event Open new state OpenSent
Sep 30 15:33:29.802486 advertising receiving-speaker only capabilty to neighbor 10.4.2.90 (External AS 65406)
Sep 30 15:33:29.802496 bgp_4byte_aspath_add_cap():155 AS4-Peer 10.4.2.90 (External AS 65406)(SEND): 4 byte AS capability added, AS 2611
Sep 30 15:33:29.802502 bgp_send: sending 67 bytes to 10.4.2.90 (External AS 65406)
Sep 30 15:33:29.802508
Sep 30 15:33:29.802508 BGP SEND 10.16.0.132+179 -> 10.4.2.90+48191
Sep 30 15:33:29.802515 BGP SEND message type 1 (Open) length 67
Sep 30 15:33:29.802521 BGP SEND version 4 as 2611 holdtime 90 id 10.0.0.122 parmlen 38
Sep 30 15:33:29.802527 BGP SEND MP capability AFI=1, SAFI=1
Sep 30 15:33:29.802544 BGP SEND MP capability AFI=1, SAFI=133
Sep 30 15:33:29.802549 BGP SEND Refresh capability, code=128
Sep 30 15:33:29.802553 BGP SEND Refresh capability, code=2
Sep 30 15:33:29.802557 BGP SEND Restart capability, code=64, time=120, flags=
Sep 30 15:33:29.802562 BGP SEND 4 Byte AS-Path capability (65), as_num 2611
Sep 30 15:33:29.802579 bgp_process_4byte_aspath_cap():288 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): 4 byte AS Cap value stored 65406

Le lundi 30 septembre 2013 15:15:19 UTC+2, Quentin Loos a écrit :

Nop, same NOTIFICATION message.

Thomas Mangin

unread,

Sep 30, 2013, 9:58:44 AM9/30/13

to exabgp...@googlegroups.com

Can you please try again ...

Thomas

Quentin Loos

unread,

Sep 30, 2013, 10:05:15 AM9/30/13

to exabgp...@googlegroups.com

Nop again. But value change to 512 ..

Sep 30 16:18:44.407011 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenSent event RecvOpen new state OpenConfirm
Sep 30 16:18:44.407016 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 16:18:44.407027
Sep 30 16:18:44.407027 BGP SEND 10.16.0.132+179 -> 10.4.2.90+46953
Sep 30 16:18:44.407034 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 16:18:44.407043 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_open
Sep 30 16:18:44.407048 task_set_socket: task BGP_65406.10.4.2.90+46953 socket 70
Sep 30 16:18:45.402176 task_process_events: recv ready for BGP_65406.10.4.2.90+46953
Sep 30 16:18:45.402203 bgp_recv_open: called for peer 10.4.2.90 (External AS 65406)
Sep 30 16:18:45.402213
Sep 30 16:18:45.402213 BGP RECV 10.4.2.90+46953 -> 10.16.0.132+179
Sep 30 16:18:45.402221 BGP RECV message type 4 (KeepAlive) length 19
Sep 30 16:18:45.402228 bgp_event: peer 10.4.2.90 (External AS 65406) old state OpenConfirm event RecvKeepAlive new state Established
Sep 30 16:18:45.402295 task_timer_reset: reset BGP_65406.10.4.2.90+46953_Hold
Sep 30 16:18:45.402305 task_timer_set_oneshot_latest: timer BGP_65406.10.4.2.90+46953_Hold interval set to 1:30
Sep 30 16:18:45.402312 task_timer_ucreate: created timer BGP_65406.10.4.2.90+46953_Traffic  flags <>
Sep 30 16:18:45.402320 task_timer_uset: timer BGP_65406.10.4.2.90+46953_Traffic <Touched> set to offset 30 jitter 20 at 16:19:10
Sep 30 16:18:45.402353 bgp_recv_change: peer 10.4.2.90 (External AS 65406) receiver changed to bgp_recv_v4_message
Sep 30 16:18:45.402360 task_set_socket: task BGP_65406.10.4.2.90+46953 socket 70
Sep 30 16:18:45.402378 bgp_send: sending 19 bytes to 10.4.2.90 (External AS 65406)
Sep 30 16:18:45.402385
Sep 30 16:18:45.402385 BGP SEND 10.16.0.132+179 -> 10.4.2.90+46953
Sep 30 16:18:45.402391 BGP SEND message type 4 (KeepAlive) length 19
Sep 30 16:18:45.402518 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 16:18:45.402525
Sep 30 16:18:45.402525 BGP SEND 10.16.0.132+179 -> 10.4.2.90+46953
Sep 30 16:18:45.402532 BGP SEND message type 2 (Update) length 23
Sep 30 16:18:45.402537 BGP SEND End of RIB: AFI 1 SAFI 1
Sep 30 16:18:45.402566 bgp_send: sending 30 bytes to 10.4.2.90 (External AS 65406)
Sep 30 16:18:45.402581
Sep 30 16:18:45.402581 BGP SEND 10.16.0.132+179 -> 10.4.2.90+46953
Sep 30 16:18:45.402588 BGP SEND message type 2 (Update) length 30
Sep 30 16:18:45.402592 BGP SEND Update PDU length 30
Sep 30 16:18:45.402600 BGP SEND flags 0x90 code MP_unreach(15): AFI/SAFI 1/133
Sep 30 16:18:45.402604 BGP SEND End of RIB: AFI 1 SAFI 133
Sep 30 16:18:45.402838 task_process_events: recv ready for BGP_65406.10.4.2.90+46953
Sep 30 16:18:45.402847 task_job_create_background: create prio 4 job Read peer for task BGP_65406.10.4.2.90+46953
Sep 30 16:18:45.402865 background dispatch running job Read peer for task BGP_65406.10.4.2.90+46953
Sep 30 16:18:45.402872 bgp_read_v4_message: receiving packet(s) from 10.4.2.90 (External AS 65406)
Sep 30 16:18:45.402881
Sep 30 16:18:45.402881 BGP RECV 10.4.2.90+46953 -> 10.16.0.132+179
Sep 30 16:18:45.402888 BGP RECV message type 2 (Update) length 53
Sep 30 16:18:45.402892 BGP RECV Update PDU length 53
Sep 30 16:18:45.402897 BGP RECV flags 0x40 code Origin(1): IGP
Sep 30 16:18:45.402908 BGP RECV flags 0xc0 code Extended Communities(16): 8006:0:0
Sep 30 16:18:45.402914 BGP RECV flags 0x80 code MP_reach(14): AFI/SAFI 1/133
Sep 30 16:18:45.402920 BGP RECV     nhop (null) len 0
Sep 30 16:18:45.402929 BGP RECV     *,10.0.0.5/48
Sep 30 16:18:45.402951 bgp_should_merge_as2_and_as4_path():2033 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): No merge of as-paths required as the peer is 4 byte as capable
Sep 30 16:18:45.402958 bgp_process_aspath_and_aggr_attr():2462 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): bgp_should_merge_as2_and_as4_path() says NO
Sep 30 16:18:45.402966 bgp_process_aspath_and_aggr_attr():2499 AS4-Peer 10.4.2.90 (External AS 65406)(RECV): Merged asp: path_len 0, path_seg_len 0, path2_len 0, path2_seg_len 0, path4_len 0, path4_seg_len 0, path_attr_len 0, path_aggr_len 0, path4_aggr_len 0, path4_flags 0x0, path_flags 0x0
Sep 30 16:18:45.403000 bgp_read_v4_update:9220: NOTIFICATION sent to 10.4.2.90 (External AS 65406): code 3 (Update Message Error) subcode 3 (missing well known attribute) value 512
Sep 30 16:18:45.403012 bgp_send: sending 23 bytes to 10.4.2.90 (External AS 65406)
Sep 30 16:18:45.403017
Sep 30 16:18:45.403017 BGP SEND 10.16.0.132+179 -> 10.4.2.90+46953
Sep 30 16:18:45.403024 BGP SEND message type 3 (Notification) length 23
Sep 30 16:18:45.403029 BGP SEND Notification code 3 (Update Message Error) subcode 3 (missing well known attribute)
Sep 30 16:18:45.403034 BGP SEND Data (2 bytes): 02 00
Sep 30 16:18:45.403061 bgp_peer_close: closing peer 10.4.2.90 (External AS 65406), state is 7 (Established)
Sep 30 16:18:45.403070 task_job_delete: delete background job Read peer for task BGP_65406.10.4.2.90+46953
Sep 30 16:18:45.403086 bgp_event: peer 10.4.2.90 (External AS 65406) old state Established event RecvUpdate new state Idle
Sep 30 16:18:45.403125 bgp_close_socket: peer 10.4.2.90 (External AS 65406)
Sep 30 16:18:45.403132 task_close: close socket 70 task BGP_65406.10.4.2.90+46953
Sep 30 16:18:45.403137 task_reset_socket: task BGP_65406.10.4.2.90+46953 socket 70
Sep 30 16:18:45.403160 task_timer_delete: BGP_65406.10.4.2.90_Traffic <Touched>
Sep 30 16:18:45.403180 task_timer_delete: BGP_65406.10.4.2.90_Hold <Touched>
Sep 30 16:18:45.403195 background dispatch completed job Read peer for task BGP_65406.10.4.2.90
Sep 30 16:18:45.403256 bgp_event: peer 10.4.2.90 (External AS 65406) old state Idle event Start new state Active
Sep 30 16:18:45.403265 task_timer_ucreate: created timer BGP_65406.10.4.2.90_Connect  flags <>
Sep 30 16:18:45.403273 task_timer_uset: timer BGP_65406.10.4.2.90_Connect <Touched> set to offset 36 at 16:19:21
Sep 30 16:19:10.182206 bgp_connect_timeout: BGP_65406.10.16.0.113_Connect
Sep 30 16:19:10.182242 bgp_connect_start: peer 10.16.0.113 (External AS 65406)
Sep 30 16:19:10.182248 bgp_event: peer 10.16.0.113 (External AS 65406) old state Active event ConnectRetry new state Connect
Sep 30 16:19:10.182330 task_get_socket: domain AF_INET  type SOCK_STREAM  protocol 0  socket 70
Sep 30 16:19:10.182342 task_set_socket: task BGP_65406.10.16.0.113 socket 70
Sep 30 16:19:10.182373 task_set_option_internal: task BGP_65406.10.16.0.113 socket 70 option NonBlocking(8) value 1
Sep 30 16:19:10.182381 task_set_option_internal: task BGP_65406.10.16.0.113 socket 70 option ReUsePort(38) value 1
Sep 30 16:19:10.182388 task_set_option_internal: task BGP_65406.10.16.0.113 socket 70 option PathMTUDiscovery(26) value 0
Sep 30 16:19:10.182396 task_set_option_internal: task BGP_65406.10.16.0.113 socket 70 option TOS(16) value 192
Sep 30 16:19:10.182413 task_addr_local: task BGP_65406.10.16.0.113 address 10.16.0.132
Sep 30 16:19:10.182442 task_connect: task BGP_65406.10.16.0.113+179 addr 10.16.0.113+179task_timer_reset: reset BGP_65406.10.16.0.113+179_Connect
Sep 30 16:19:10.182464 task_timer_set_oneshot_latest: timer BGP_65406.10.16.0.113+179_Connect interval set to 32
Sep 30 16:19:10.182878 task_process_events: connect ready for BGP_65406.10.16.0.113+179
Sep 30 16:19:10.182889 bgp_connect_complete: error connecting to 10.16.0.113 (External AS 65406): Socket is not connected
Sep 30 16:19:10.182894 bgp_close_socket: peer 10.16.0.113 (External AS 65406)
Sep 30 16:19:10.182899 task_close: close socket 70 task BGP_65406.10.16.0.113+179
Sep 30 16:19:10.182904 task_reset_socket: task BGP_65406.10.16.0.113+179 socket 70
Sep 30 16:19:10.182924 bgp_event: peer 10.16.0.113 (External AS 65406) old state Connect event OpenFail new state Idle
Sep 30 16:19:10.182960 bgp_event: peer 10.16.0.113 (External AS 65406) old state Idle event Start new state Active

Thomas Mangin

unread,

Sep 30, 2013, 12:30:31 PM9/30/13

to exabgp...@googlegroups.com

Not sure my previous mail from my phone made it ...

Could you check if you were using the latest version as I done two changes since your previous attempt.

Furthermore are you using an IBGP or EBGP session, if it is EBGP please let me know if changing to IBGP helps.

Thomas

Sent from my iPad

Quentin Loos

unread,

Oct 1, 2013, 3:34:01 AM10/1/13

to exabgp...@googlegroups.com

Yeah i It works ! Thank you very much !

You last commit resolved my issue (94ca94303869b784e934cef11b4102def993e7ce).

lab@router> show route table inetflow.0

inetflow.0: 1 destinations, 1 routes (1 active, 0 holddown, 0 hidden)
+ = Active Route, - = Last Active, * = Both

*,10.0.0.5/term:1            
                   *[BGP/170] 00:00:08, localpref 100, from 10.4.2.90
                      AS path: 65406 I
                      Fictitious

I'll try to etablish iBGP session too, to verify if I receive an UPDATE message with the flow.

Thank you again.

Thomas Mangin

unread,

Oct 1, 2013, 3:50:20 AM10/1/13

to exabgp...@googlegroups.com

Thank you for your help in fixing this issue.

Thomas

Reply all

Reply to author

Forward

0 new messages