[quagga-users 12974] Fwd: ospf daemon not learning Type3 LS updates

vishal kumar vishal3.kumar at gmail.com
Fri Sep 28 21:29:22 BST 2012


Missed to attach the setup details file..
Sorry for inconvenience.

Thanks
Vishal

---------- Forwarded message ----------
From: vishal kumar <vishal3.kumar at gmail.com>
Date: Sat, Sep 29, 2012 at 1:51 AM
Subject: ospf daemon not learning Type3 LS updates
To: quagga-users-owner at lists.quagga.net, quagga-dev at lists.quagga.net


Hi,

We are using quagga (version: 0.99.21) as routing stack at our servers and
our customer is facing one issue which is very much reproducible in our HA
setup.
In a HA setup when the primary router is going down and secondary is taking
place in the network, ospf is started on that.

In this case the ospf daemon is not learning the routes received from type3
LS updated.
I have attached the setup details and the logs from the setup.

In case of failover when DUT1 goes down and DUT2 starts acting as ospf peer
for Router1 and Router2. In this case when  ospf LS updates type3 are
exchanged between FW2 and Router1, Router1 is not able to install the type3
LS update routes (for subnet 10.157.96.0/24) sent from FW3. But when ospf
at either DUT2 or Router1 is restarted its start working fine. I have also
seen this issue (very intermittent) with standalone DUT by just triggering
a OSPF restart at the DUT, so this should be independent of HA. For
resolving this issue I am comparing ospf logs with the success scenario. I
have attached the setup details and ospf logs from Router1 in both success
and failure scenario.

The faulty logs clearly shows that the Type3 routes are first are not
configured because ABR has not been discovered at Router1 then they are
MAXAGE-out at the end


In the faulty logs please look for statement:

<Problem in learning 10.157.96.0 network>
2012/09/28 07:13:07 OSPF: process_summary_lsa(): LS ID: 10.157.96.0 (the
type3 route)

The comparison with the good case logs clearly shows the difference. Please
tell if i need to share any further information. We will appreciate a quick
response.

The same issue is occurring for Typer5 LS updates too.

Thanks and Regards
Vishal
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.quagga.net/pipermail/quagga-users/attachments/20120929/b2b99978/attachment-0001.html>
-------------- next part --------------

2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Init (2-WayReceived)
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: State change Init -> ExStart (2-WayReceived)
2012/09/28 07:13:07 OSPF: DR-Election[1st]: Backup 10.157.91.30
2012/09/28 07:13:07 OSPF: DR-Election[1st]: DR     10.157.91.40
2012/09/28 07:13:07 OSPF: ISM[em1:10.157.91.40]: DR (NeighborChange)
2012/09/28 07:13:07 OSPF: Packet[DD]: Neighbor 10.157.91.30: Initial DBD from Slave, ignoring.
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: ExStart (PacketReceived)
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: ExStart (AdjOK?)
2012/09/28 07:13:07 OSPF: Packet[DD]: Neighbor 10.157.91.30 Negotiation done (Master).
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: ExStart (NegotiationDone)
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: State change ExStart -> Exchange (NegotiationDone)
2012/09/28 07:13:07 OSPF: Packet [DD:RECV]: LSA received Type 1, ID 10.157.91.30 is not recent.
2012/09/28 07:13:07 OSPF: LSA: freed 0x820e7d0
2012/09/28 07:13:07 OSPF: LSA[Type1:10.157.91.30]: data freed 0x820d990
2012/09/28 07:13:07 OSPF: Packet [DD:RECV]: LSA received Type 3, ID 10.157.92.0 is not recent.
2012/09/28 07:13:07 OSPF: LSA: freed 0x820e7d0
2012/09/28 07:13:07 OSPF: LSA[Type3:10.157.92.0]: data freed 0x820d990
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Exchange (PacketReceived)
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Exchange (PacketReceived)
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Exchange (ExchangeDone)
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: State change Exchange -> Full (ExchangeDone)
2012/09/28 07:13:07 OSPF: ospf_check_abr_status(): Start
2012/09/28 07:13:07 OSPF: ospf_check_abr_status(): looked through areas
2012/09/28 07:13:07 OSPF: ospf_check_abr_status(): bb_configured: 0
2012/09/28 07:13:07 OSPF: ospf_check_abr_status(): bb_act_attached: 0
2012/09/28 07:13:07 OSPF: ospf_check_abr_status(): areas_configured: 1
2012/09/28 07:13:07 OSPF: ospf_check_abr_status(): areas_act_attached: 1
2012/09/28 07:13:07 OSPF: nsm_change_state(10.157.91.30, Exchange -> Full): scheduling new router-LSA origination
2012/09/28 07:13:07 OSPF: [router-LSA]: (router-LSA area update)
2012/09/28 07:13:07 OSPF: LSA[Type1]: Create router-LSA instance
2012/09/28 07:13:07 OSPF: counting fully adjacent virtual neighbors in area 0.0.0.1
2012/09/28 07:13:07 OSPF: there are 0 of them
2012/09/28 07:13:07 OSPF: LSA: freed 0x81e5e90
2012/09/28 07:13:07 OSPF: LSA[Type1:10.157.91.40]: data freed 0x8203e80
2012/09/28 07:13:07 OSPF: LSA[Refresh]: lsa 10.157.91.40 with age 0 added to index 70
2012/09/28 07:13:07 OSPF: LSA[Refresh:10.157.91.40]: ospf_refresher_register_lsa(): setting refresh_list on lsa 0x820e1d0 (slod 70)
2012/09/28 07:13:07 OSPF: SPF: calculation timer scheduled
2012/09/28 07:13:07 OSPF: SPF: calculation timer delay = 200
2012/09/28 07:13:07 OSPF: LSA[Type1,id(10.157.91.40),ar(10.157.91.40)]: Install router-LSA to Area 0.0.0.1
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering int em1:10.157.91.40, INBR(NULL), LSA[Type1,id(10.157.91.40),ar(10.157.91.40)]
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.30 (Full)
2012/09/28 07:13:07 OSPF: RXmtL(0)++, NBR(10.157.91.30), LSA[Type1,id(10.157.91.40),ar(10.157.91.40)]
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.40 (2-Way)
2012/09/28 07:13:07 OSPF: LSA[Type1:10.157.91.40]: router-LSA refresh
2012/09/28 07:13:07 OSPF:   LSA Header
2012/09/28 07:13:07 OSPF:     LS age 0
2012/09/28 07:13:07 OSPF:     Options 2 (*|-|-|-|-|-|E|*)
2012/09/28 07:13:07 OSPF:     LS type 1 (router-LSA)
2012/09/28 07:13:07 OSPF:     Link State ID 10.157.91.40
2012/09/28 07:13:07 OSPF:     Advertising Router 10.157.91.40
2012/09/28 07:13:07 OSPF:     LS sequence number 0x8000008a
2012/09/28 07:13:07 OSPF:     LS checksum 0xe330
2012/09/28 07:13:07 OSPF:     length 36
2012/09/28 07:13:07 OSPF: LSA[Type2]: Create network-LSA instance
2012/09/28 07:13:07 OSPF: LSA[Refresh]: lsa 10.157.91.40 with age 0 added to index 85
2012/09/28 07:13:07 OSPF: LSA[Refresh:10.157.91.40]: ospf_refresher_register_lsa(): setting refresh_list on lsa 0x820e350 (slod 85)
2012/09/28 07:13:07 OSPF: SPF: calculation timer scheduled
2012/09/28 07:13:07 OSPF: SPF: calculation timer is already scheduled: 0x8132d90
2012/09/28 07:13:07 OSPF: LSA[Type2,id(10.157.91.40),ar(10.157.91.40)]: Install network-LSA to Area 0.0.0.1
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering int em1:10.157.91.40, INBR(NULL), LSA[Type2,id(10.157.91.40),ar(10.157.91.40)]
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.30 (Full)
2012/09/28 07:13:07 OSPF: RXmtL(1)++, NBR(10.157.91.30), LSA[Type2,id(10.157.91.40),ar(10.157.91.40)]
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.40 (2-Way)
2012/09/28 07:13:07 OSPF: LSA[Type2:10.157.91.40]: Originate network-LSA 0x820e350
2012/09/28 07:13:07 OSPF:   LSA Header
2012/09/28 07:13:07 OSPF:     LS age 0
2012/09/28 07:13:07 OSPF:     Options 2 (*|-|-|-|-|-|E|*)
2012/09/28 07:13:07 OSPF:     LS type 2 (network-LSA)
2012/09/28 07:13:07 OSPF:     Link State ID 10.157.91.40
2012/09/28 07:13:07 OSPF:     Advertising Router 10.157.91.40
2012/09/28 07:13:07 OSPF:     LS sequence number 0x80000001
2012/09/28 07:13:07 OSPF:     LS checksum 0xa314
2012/09/28 07:13:07 OSPF:     length 32
2012/09/28 07:13:07 OSPF: ospf_ls_upd_send_queue start
2012/09/28 07:13:07 OSPF: listcount = 7, dst 224.0.0.5
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: Start
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: Stop
2012/09/28 07:13:07 OSPF: ospf_ls_upd_send_queue stop
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:07 OSPF: RXmtL(2)--, NBR(10.157.91.30), LSA[Type1,id(10.157.91.40),ar(10.157.91.40)]
2012/09/28 07:13:07 OSPF: LSA: freed 0x820e770
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:07 OSPF: ospf_ls_upd_send_queue start
2012/09/28 07:13:07 OSPF: listcount = 1, dst 224.0.0.5
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: Start
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 07:13:07 OSPF: ospf_make_ls_upd: Stop
2012/09/28 07:13:07 OSPF: ospf_ls_upd_send_queue stop
2012/09/28 07:13:07 OSPF: LSA[Type2:10.157.91.30]: 0x81e5e90 new LSA created with Link State Update
2012/09/28 07:13:07 OSPF: LSA[Flooding]: start, NBR 10.157.91.30 (Full), cur(0x820e2f0), New-LSA[Type2,id(10.157.91.30),ar(10.157.91.30)]
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering int em1:10.157.91.40, INBR(10.157.91.30), LSA[Type2,id(10.157.91.30),ar(10.157.91.30)]
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.30 (Full)
2012/09/28 07:13:07 OSPF: Skip this neighbor: inbr == onbr
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.40 (2-Way)
2012/09/28 07:13:07 OSPF: LSA: freed 0x820e2f0
2012/09/28 07:13:07 OSPF: LSA[Type2:10.157.91.30]: data freed 0x820dad0
2012/09/28 07:13:07 OSPF: SPF: calculation timer scheduled
2012/09/28 07:13:07 OSPF: SPF: calculation timer is already scheduled: 0x8132d90
2012/09/28 07:13:07 OSPF: LSA[Type2,id(10.157.91.30),ar(10.157.91.30)]: Install network-LSA to Area 0.0.0.1
2012/09/28 07:13:07 OSPF: LSA[Type2:10.157.91.30]: Install LSA 0x0x81e5e90, MaxAge
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering int em1:10.157.91.40, INBR(NULL), LSA[Type2,id(10.157.91.30),ar(10.157.91.30)]
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.30 (Full)
2012/09/28 07:13:07 OSPF: Skip this neighbor: lsah->adv_router == onbr
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.40 (2-Way)
2012/09/28 07:13:07 OSPF: LSA[Type2,id(10.157.91.30),ar(10.157.91.30)]: MaxAge LSA remover scheduled.
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:07 OSPF: SPF: Timer (SPF calculation expire)
2012/09/28 07:13:07 OSPF: ospf_spf_calculate: Start
2012/09/28 07:13:07 OSPF: ospf_spf_calculate: running Dijkstra for area 0.0.0.1
2012/09/28 07:13:07 OSPF: ospf_vertex_new: Created Router vertex 10.157.91.40
2012/09/28 07:13:07 OSPF: ospf_spf_next: Next vertex of Router vertex 10.157.91.40
2012/09/28 07:13:07 OSPF: Looking up Network LSA, ID: 10.157.91.40
2012/09/28 07:13:07 OSPF: found the LSA
2012/09/28 07:13:07 OSPF: ospf_vertex_new: Created Network vertex 10.157.91.40
2012/09/28 07:13:07 OSPF: ospf_nexthop_calculation(): Start
2012/09/28 07:13:07 OSPF: V (parent): Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:07 OSPF: W (dest)  : Network vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:07 OSPF: V->W distance: 10
2012/09/28 07:13:07 OSPF: ospf_spf_add_parent: Adding 10.157.91.40 as parent of 10.157.91.40
2012/09/28 07:13:07 OSPF: ospf_spf_next: Next vertex of Network vertex 10.157.91.40
2012/09/28 07:13:07 OSPF: found Router LSA 10.157.91.30
2012/09/28 07:13:07 OSPF: The LSA doesn't have a link back
2012/09/28 07:13:07 OSPF: found Router LSA 10.157.91.40
2012/09/28 07:13:07 OSPF: The LSA is already in SPF
2012/09/28 07:13:07 OSPF: SPF Result: 0 [R] 10.157.91.40
2012/09/28 07:13:07 OSPF: SPF Result: 1 [N] 10.157.91.40/24
2012/09/28 07:13:07 OSPF:  nexthop 0x820cef0 0.0.0.0 em1:10.157.91.40
2012/09/28 07:13:07 OSPF: ========== OSPF routing table ==========
2012/09/28 07:13:07 OSPF: N 10.157.91.0/24	0.0.0.1	intra-area	10
2012/09/28 07:13:07 OSPF:   -> 0.0.0.0
2012/09/28 07:13:07 OSPF: ========================================
2012/09/28 07:13:07 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:07 OSPF: ospf_process_stubs():processing router LSA, id: 10.157.91.40
2012/09/28 07:13:07 OSPF: ospf_process_stubs(): we have 1 links to process
2012/09/28 07:13:07 OSPF: ospf_process_stubs(): after examining links:  Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:07 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:07 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:07 OSPF: ospf_process_stubs(): after examining links:  Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:07 OSPF: parent 10.157.91.40 backlink 1 nexthop 0.0.0.0  interface em1:10.157.91.40
2012/09/28 07:13:07 OSPF: ospf_spf_calculate Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:07 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 1
2012/09/28 07:13:07 OSPF: ospf_vertex_free: Free Router vertex 10.157.91.40
2012/09/28 07:13:07 OSPF: ospf_vertex_free: Free Network vertex 10.157.91.40
2012/09/28 07:13:07 OSPF: ospf_spf_calculate: Stop. 0 vertices
2012/09/28 07:13:07 OSPF: ospf_ia_routing():start
2012/09/28 07:13:07 OSPF: ospf_ia_routing():not ABR, considering all areas




2012/09/28 07:13:07 OSPF: process_summary_lsa(): LS ID: 10.157.92.0

<Problem in learning 10.157.96.0 network>

2012/09/28 07:13:07 OSPF: process_summary_lsa(): LS ID: 10.157.96.0
2012/09/28 07:13:07 OSPF: Pruning unreachable networks
2012/09/28 07:13:07 OSPF: Pruning unreachable routers
2012/09/28 07:13:07 OSPF: Route: Router Routing Table free
2012/09/28 07:13:07 OSPF: SPF: calculation complete
2012/09/28 07:13:07 OSPF: LSA[Type3:10.157.96.0]: 0x81e5fb0 new LSA created with Link State Update
2012/09/28 07:13:07 OSPF: LSA[Flooding]: start, NBR 10.157.91.30 (Full), cur(0x820e470), New-LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering int em1:10.157.91.40, INBR(10.157.91.30), LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.30 (Full)
2012/09/28 07:13:07 OSPF: Skip this neighbor: inbr == onbr
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.40 (2-Way)
2012/09/28 07:13:07 OSPF: LSA: freed 0x820e470
2012/09/28 07:13:07 OSPF: LSA[Type3:10.157.96.0]: data freed 0x820db10
2012/09/28 07:13:07 OSPF: SPF: calculation timer scheduled
2012/09/28 07:13:07 OSPF: SPF: calculation timer delay = 974
2012/09/28 07:13:07 OSPF: ospf_summary_lsa_install(): SPF scheduled
2012/09/28 07:13:07 OSPF: LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]: Install summary-LSA to Area 0.0.0.1
2012/09/28 07:13:07 OSPF: LSA[Type3:10.157.96.0]: Install LSA 0x0x81e5fb0, MaxAge
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering int em1:10.157.91.40, INBR(NULL), LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.30 (Full)
2012/09/28 07:13:07 OSPF: Skip this neighbor: lsah->adv_router == onbr
2012/09/28 07:13:07 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.40 (2-Way)
2012/09/28 07:13:07 OSPF: LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]: MaxAge LSA remover scheduled.
2012/09/28 07:13:07 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:08 OSPF: LSA: freed 0x820e230
2012/09/28 07:13:08 OSPF: RXmtL(1)--, NBR(10.157.91.30), LSA[Type2,id(10.157.91.40),ar(10.157.91.40)]
2012/09/28 07:13:08 OSPF: LSA: freed 0x820e230
2012/09/28 07:13:08 OSPF: LSA: freed 0x820e230
2012/09/28 07:13:08 OSPF: LSA: freed 0x820e230
2012/09/28 07:13:08 OSPF: LSA: freed 0x820e230
2012/09/28 07:13:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:08 OSPF: SPF: Timer (SPF calculation expire)
2012/09/28 07:13:08 OSPF: ospf_spf_calculate: Start
2012/09/28 07:13:08 OSPF: ospf_spf_calculate: running Dijkstra for area 0.0.0.1
2012/09/28 07:13:08 OSPF: ospf_vertex_new: Created Router vertex 10.157.91.40
2012/09/28 07:13:08 OSPF: ospf_spf_next: Next vertex of Router vertex 10.157.91.40
2012/09/28 07:13:08 OSPF: Looking up Network LSA, ID: 10.157.91.40
2012/09/28 07:13:08 OSPF: found the LSA
2012/09/28 07:13:08 OSPF: ospf_vertex_new: Created Network vertex 10.157.91.40
2012/09/28 07:13:08 OSPF: ospf_nexthop_calculation(): Start
2012/09/28 07:13:08 OSPF: V (parent): Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:08 OSPF: W (dest)  : Network vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:08 OSPF: V->W distance: 10
2012/09/28 07:13:08 OSPF: ospf_spf_add_parent: Adding 10.157.91.40 as parent of 10.157.91.40
2012/09/28 07:13:08 OSPF: ospf_spf_next: Next vertex of Network vertex 10.157.91.40
2012/09/28 07:13:08 OSPF: found Router LSA 10.157.91.30
2012/09/28 07:13:08 OSPF: The LSA doesn't have a link back
2012/09/28 07:13:08 OSPF: found Router LSA 10.157.91.40
2012/09/28 07:13:08 OSPF: The LSA is already in SPF
2012/09/28 07:13:08 OSPF: SPF Result: 0 [R] 10.157.91.40
2012/09/28 07:13:08 OSPF: SPF Result: 1 [N] 10.157.91.40/24
2012/09/28 07:13:08 OSPF:  nexthop 0x820ce30 0.0.0.0 em1:10.157.91.40
2012/09/28 07:13:08 OSPF: ========== OSPF routing table ==========
2012/09/28 07:13:08 OSPF: N 10.157.91.0/24	0.0.0.1	intra-area	10
2012/09/28 07:13:08 OSPF:   -> 0.0.0.0
2012/09/28 07:13:08 OSPF: ========================================
2012/09/28 07:13:08 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:08 OSPF: ospf_process_stubs():processing router LSA, id: 10.157.91.40
2012/09/28 07:13:08 OSPF: ospf_process_stubs(): we have 1 links to process
2012/09/28 07:13:08 OSPF: ospf_process_stubs(): after examining links:  Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:08 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:08 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:08 OSPF: ospf_process_stubs(): after examining links:  Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:08 OSPF: parent 10.157.91.40 backlink 1 nexthop 0.0.0.0  interface em1:10.157.91.40
2012/09/28 07:13:08 OSPF: ospf_spf_calculate Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:08 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 1
2012/09/28 07:13:08 OSPF: ospf_vertex_free: Free Router vertex 10.157.91.40
2012/09/28 07:13:08 OSPF: ospf_vertex_free: Free Network vertex 10.157.91.40
2012/09/28 07:13:08 OSPF: ospf_spf_calculate: Stop. 0 vertices
2012/09/28 07:13:08 OSPF: ospf_ia_routing():start
2012/09/28 07:13:08 OSPF: ospf_ia_routing():not ABR, considering all areas
2012/09/28 07:13:08 OSPF: process_summary_lsa(): LS ID: 10.157.92.0
2012/09/28 07:13:08 OSPF: process_summary_lsa(): LS ID: 10.157.96.0
2012/09/28 07:13:08 OSPF: Pruning unreachable networks
2012/09/28 07:13:08 OSPF: Pruning unreachable routers
2012/09/28 07:13:08 OSPF: Route: Router Routing Table free
2012/09/28 07:13:08 OSPF: SPF: calculation complete
2012/09/28 07:13:08 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:13:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:13:08 OSPF: DR-Election[1st]: Backup 10.157.91.30
2012/09/28 07:13:08 OSPF: DR-Election[1st]: DR     10.157.91.40
2012/09/28 07:13:08 OSPF: ISM[em1:10.157.91.40]: DR (NeighborChange)
2012/09/28 07:13:12 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:12 OSPF: ospf_ls_upd_send_queue start
2012/09/28 07:13:12 OSPF: listcount = 1, dst 224.0.0.5
2012/09/28 07:13:12 OSPF: ospf_make_ls_upd: Start
2012/09/28 07:13:12 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 07:13:12 OSPF: ospf_make_ls_upd: Stop
2012/09/28 07:13:12 OSPF: ospf_ls_upd_send_queue stop
2012/09/28 07:13:12 OSPF: LSA[Type1:10.157.91.30]: 0x81e5d70 new LSA created with Link State Update
2012/09/28 07:13:12 OSPF: LSA[Type1:10.157.91.30]: 0x820e3b0 new LSA created with Link State Update
2012/09/28 07:13:12 OSPF: LSA[Flooding]: start, NBR 10.157.91.30 (Full), cur(0x820e290), New-LSA[Type1,id(10.157.91.30),ar(10.157.91.30)]
2012/09/28 07:13:12 OSPF: ospf_flood_through_interface(): considering int em1:10.157.91.40, INBR(10.157.91.30), LSA[Type1,id(10.157.91.30),ar(10.157.91.30)]
2012/09/28 07:13:12 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.30 (Full)
2012/09/28 07:13:12 OSPF: Skip this neighbor: inbr == onbr
2012/09/28 07:13:12 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.40 (2-Way)
2012/09/28 07:13:12 OSPF: LSA: freed 0x820e290
2012/09/28 07:13:12 OSPF: LSA[Type1:10.157.91.30]: data freed 0x8203ed0
2012/09/28 07:13:12 OSPF: SPF: calculation timer scheduled
2012/09/28 07:13:12 OSPF: SPF: calculation timer delay = 200
2012/09/28 07:13:12 OSPF: LSA[Type1,id(10.157.91.30),ar(10.157.91.30)]: Install router-LSA to Area 0.0.0.1
2012/09/28 07:13:12 OSPF: LSA[Flooding]: start, NBR 10.157.91.30 (Full), cur(0x81e5d70), New-LSA[Type1,id(10.157.91.30),ar(10.157.91.30)]
2012/09/28 07:13:12 OSPF: LSA[Flooding]: LSA is received recently.
2012/09/28 07:13:12 OSPF: ospf_lsa_discard() in ospf_ls_upd() point 4: lsa 0x820e3b0 Type-1
2012/09/28 07:13:12 OSPF: LSA: freed 0x820e3b0
2012/09/28 07:13:12 OSPF: LSA[Type1:10.157.91.30]: data freed 0x8203f70
2012/09/28 07:13:12 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:12 OSPF: LSA: freed 0x820e290
2012/09/28 07:13:12 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:12 OSPF: SPF: Timer (SPF calculation expire)
2012/09/28 07:13:12 OSPF: ospf_spf_calculate: Start
2012/09/28 07:13:12 OSPF: ospf_spf_calculate: running Dijkstra for area 0.0.0.1
2012/09/28 07:13:12 OSPF: ospf_vertex_new: Created Router vertex 10.157.91.40
2012/09/28 07:13:12 OSPF: ospf_spf_next: Next vertex of Router vertex 10.157.91.40
2012/09/28 07:13:12 OSPF: Looking up Network LSA, ID: 10.157.91.40
2012/09/28 07:13:12 OSPF: found the LSA
2012/09/28 07:13:12 OSPF: ospf_vertex_new: Created Network vertex 10.157.91.40
2012/09/28 07:13:12 OSPF: ospf_nexthop_calculation(): Start
2012/09/28 07:13:12 OSPF: V (parent): Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:12 OSPF: W (dest)  : Network vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:12 OSPF: V->W distance: 10
2012/09/28 07:13:12 OSPF: ospf_spf_add_parent: Adding 10.157.91.40 as parent of 10.157.91.40
2012/09/28 07:13:12 OSPF: ospf_spf_next: Next vertex of Network vertex 10.157.91.40
2012/09/28 07:13:12 OSPF: found Router LSA 10.157.91.30
2012/09/28 07:13:12 OSPF: The LSA doesn't have a link back
2012/09/28 07:13:12 OSPF: found Router LSA 10.157.91.40
2012/09/28 07:13:12 OSPF: The LSA is already in SPF
2012/09/28 07:13:12 OSPF: SPF Result: 0 [R] 10.157.91.40
2012/09/28 07:13:12 OSPF: SPF Result: 1 [N] 10.157.91.40/24
2012/09/28 07:13:12 OSPF:  nexthop 0x820ce30 0.0.0.0 em1:10.157.91.40
2012/09/28 07:13:12 OSPF: ========== OSPF routing table ==========
2012/09/28 07:13:12 OSPF: N 10.157.91.0/24	0.0.0.1	intra-area	10
2012/09/28 07:13:12 OSPF:   -> 0.0.0.0
2012/09/28 07:13:12 OSPF: ========================================
2012/09/28 07:13:12 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:12 OSPF: ospf_process_stubs():processing router LSA, id: 10.157.91.40
2012/09/28 07:13:12 OSPF: ospf_process_stubs(): we have 1 links to process
2012/09/28 07:13:12 OSPF: ospf_process_stubs(): after examining links:  Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:12 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:12 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:12 OSPF: ospf_process_stubs(): after examining links:  Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:12 OSPF: parent 10.157.91.40 backlink 1 nexthop 0.0.0.0  interface em1:10.157.91.40
2012/09/28 07:13:12 OSPF: ospf_spf_calculate Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:12 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 1
2012/09/28 07:13:12 OSPF: ospf_vertex_free: Free Router vertex 10.157.91.40
2012/09/28 07:13:12 OSPF: ospf_vertex_free: Free Network vertex 10.157.91.40
2012/09/28 07:13:12 OSPF: ospf_spf_calculate: Stop. 0 vertices
2012/09/28 07:13:12 OSPF: ospf_ia_routing():start
2012/09/28 07:13:12 OSPF: ospf_ia_routing():not ABR, considering all areas
2012/09/28 07:13:12 OSPF: process_summary_lsa(): LS ID: 10.157.92.0
2012/09/28 07:13:12 OSPF: process_summary_lsa(): LS ID: 10.157.96.0
2012/09/28 07:13:12 OSPF: Pruning unreachable networks
2012/09/28 07:13:12 OSPF: Pruning unreachable routers
2012/09/28 07:13:12 OSPF: Route: Router Routing Table free
2012/09/28 07:13:12 OSPF: SPF: calculation complete
2012/09/28 07:13:17 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:13:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 158
2012/09/28 07:13:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 157
2012/09/28 07:13:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:13:17 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:13:17 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:13:17 OSPF: LSA[Type1:10.157.91.30]: 0x820e2f0 new LSA created with Link State Update
2012/09/28 07:13:17 OSPF: LSA[Flooding]: start, NBR 10.157.91.30 (Full), cur(0x81e5d70), New-LSA[Type1,id(10.157.91.30),ar(10.157.91.30)]
2012/09/28 07:13:17 OSPF: ospf_flood_through_interface(): considering int em1:10.157.91.40, INBR(10.157.91.30), LSA[Type1,id(10.157.91.30),ar(10.157.91.30)]
2012/09/28 07:13:17 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.30 (Full)
2012/09/28 07:13:17 OSPF: Skip this neighbor: inbr == onbr
2012/09/28 07:13:17 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.40 (2-Way)
2012/09/28 07:13:17 OSPF: LSA: freed 0x81e5d70
2012/09/28 07:13:17 OSPF: LSA[Type1:10.157.91.30]: data freed 0x8203e30
2012/09/28 07:13:17 OSPF: SPF: calculation timer scheduled
2012/09/28 07:13:17 OSPF: SPF: calculation timer delay = 200
2012/09/28 07:13:17 OSPF: LSA[Type1,id(10.157.91.30),ar(10.157.91.30)]: Install router-LSA to Area 0.0.0.1
2012/09/28 07:13:17 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:17 OSPF: SPF: Timer (SPF calculation expire)
2012/09/28 07:13:17 OSPF: ospf_spf_calculate: Start
2012/09/28 07:13:17 OSPF: ospf_spf_calculate: running Dijkstra for area 0.0.0.1
2012/09/28 07:13:17 OSPF: ospf_vertex_new: Created Router vertex 10.157.91.40
2012/09/28 07:13:17 OSPF: ospf_spf_next: Next vertex of Router vertex 10.157.91.40
2012/09/28 07:13:17 OSPF: Looking up Network LSA, ID: 10.157.91.40
2012/09/28 07:13:17 OSPF: found the LSA
2012/09/28 07:13:17 OSPF: ospf_vertex_new: Created Network vertex 10.157.91.40
2012/09/28 07:13:17 OSPF: ospf_nexthop_calculation(): Start
2012/09/28 07:13:17 OSPF: V (parent): Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:17 OSPF: W (dest)  : Network vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:17 OSPF: V->W distance: 10
2012/09/28 07:13:17 OSPF: ospf_spf_add_parent: Adding 10.157.91.40 as parent of 10.157.91.40
2012/09/28 07:13:17 OSPF: ospf_spf_next: Next vertex of Network vertex 10.157.91.40
2012/09/28 07:13:17 OSPF: found Router LSA 10.157.91.30
2012/09/28 07:13:17 OSPF: ospf_vertex_new: Created Router vertex 10.157.91.30
2012/09/28 07:13:17 OSPF: ospf_nexthop_calculation(): Start
2012/09/28 07:13:17 OSPF: V (parent): Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:17 OSPF: parent 10.157.91.40 backlink 1 nexthop 0.0.0.0  interface em1:10.157.91.40
2012/09/28 07:13:17 OSPF: W (dest)  : Router vertex 10.157.91.30  distance 0 flags 0
2012/09/28 07:13:17 OSPF: V->W distance: 10
2012/09/28 07:13:17 OSPF: ospf_spf_add_parent: Adding 10.157.91.40 as parent of 10.157.91.30
2012/09/28 07:13:17 OSPF: found Router LSA 10.157.91.40
2012/09/28 07:13:17 OSPF: The LSA is already in SPF
2012/09/28 07:13:17 OSPF: ospf_intra_add_router: Start
2012/09/28 07:13:17 OSPF: ospf_intra_add_router: LS ID: 10.157.91.30
2012/09/28 07:13:17 OSPF: ospf_vl_up_check(): Start
2012/09/28 07:13:17 OSPF: ospf_vl_up_check(): Router ID is 10.157.91.30
2012/09/28 07:13:17 OSPF: ospf_vl_up_check(): Area is 0.0.0.1
2012/09/28 07:13:17 OSPF: ospf_intra_add_router: talking about 10.157.91.30/32
2012/09/28 07:13:17 OSPF: ospf_intra_add_router: Stop
2012/09/28 07:13:17 OSPF: ospf_spf_next: Next vertex of Router vertex 10.157.91.30
2012/09/28 07:13:17 OSPF: Looking up Network LSA, ID: 10.157.91.40
2012/09/28 07:13:17 OSPF: found the LSA
2012/09/28 07:13:17 OSPF: The LSA is already in SPF
2012/09/28 07:13:17 OSPF: SPF Result: 0 [R] 10.157.91.40
2012/09/28 07:13:17 OSPF: SPF Result: 1 [N] 10.157.91.40/24
2012/09/28 07:13:17 OSPF:  nexthop 0x820ce30 0.0.0.0 em1:10.157.91.40
2012/09/28 07:13:17 OSPF: SPF Result: 2 [R] 10.157.91.30
2012/09/28 07:13:17 OSPF:  nexthop 0x820f0b0 10.157.91.30 em1:10.157.91.40
2012/09/28 07:13:17 OSPF: ========== OSPF routing table ==========
2012/09/28 07:13:17 OSPF: N 10.157.91.0/24	0.0.0.1	intra-area	10
2012/09/28 07:13:17 OSPF:   -> 0.0.0.0
2012/09/28 07:13:17 OSPF: ========================================
2012/09/28 07:13:17 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:17 OSPF: ospf_process_stubs():processing router LSA, id: 10.157.91.40
2012/09/28 07:13:17 OSPF: ospf_process_stubs(): we have 1 links to process
2012/09/28 07:13:17 OSPF: ospf_process_stubs(): after examining links:  Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:17 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:17 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:17 OSPF: ospf_process_stubs(): after examining links:  Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:17 OSPF: parent 10.157.91.40 backlink 1 nexthop 0.0.0.0  interface em1:10.157.91.40
2012/09/28 07:13:17 OSPF:  child: Router vertex 10.157.91.30  distance 10 flags 0
2012/09/28 07:13:17 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:17 OSPF: ospf_process_stubs():processing router LSA, id: 10.157.91.30
2012/09/28 07:13:17 OSPF: ospf_process_stubs(): we have 1 links to process
2012/09/28 07:13:17 OSPF: ospf_process_stubs(): after examining links:  Router vertex 10.157.91.30  distance 10 flags 0
2012/09/28 07:13:17 OSPF: parent 10.157.91.40 backlink 0 nexthop 10.157.91.30  interface em1:10.157.91.40
2012/09/28 07:13:17 OSPF: ospf_spf_calculate Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:17 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 1
2012/09/28 07:13:17 OSPF: ospf_vertex_free: Free Router vertex 10.157.91.40
2012/09/28 07:13:17 OSPF: ospf_vertex_free: Free Network vertex 10.157.91.40
2012/09/28 07:13:17 OSPF: ospf_vertex_free: Free Router vertex 10.157.91.30
2012/09/28 07:13:17 OSPF: ospf_spf_calculate: Stop. 0 vertices
2012/09/28 07:13:17 OSPF: ospf_ia_routing():start
2012/09/28 07:13:17 OSPF: ospf_ia_routing():not ABR, considering all areas
2012/09/28 07:13:17 OSPF: process_summary_lsa(): LS ID: 10.157.92.0
2012/09/28 07:13:17 OSPF: ospf_ia_network_route(): processing summary route to 10.157.92.0/24
2012/09/28 07:13:17 OSPF: ospf_ia_network_route(): add new route to 10.157.92.0/24
2012/09/28 07:13:17 OSPF: process_summary_lsa(): LS ID: 10.157.96.0
2012/09/28 07:13:17 OSPF: Pruning unreachable networks
2012/09/28 07:13:17 OSPF: Pruning unreachable routers
2012/09/28 07:13:17 OSPF: Zebra: Route add 10.157.92.0/24 nexthop 10.157.91.30
2012/09/28 07:13:17 OSPF: Route: Router Routing Table free
2012/09/28 07:13:17 OSPF: SPF: calculation complete
2012/09/28 07:13:18 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:13:18 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:18 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:13:26 OSPF: LSA[Type2,id(10.157.91.30),ar(10.157.91.30)]: is MaxAge
2012/09/28 07:13:26 OSPF: SPF: calculation timer scheduled
2012/09/28 07:13:26 OSPF: SPF: calculation timer delay = 200
2012/09/28 07:13:26 OSPF: LSA[Type2:10.157.91.30]: 0x81e5e90 already exists on MaxAge LSA list
2012/09/28 07:13:26 OSPF: LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]: is MaxAge
2012/09/28 07:13:26 OSPF: SPF: calculation timer scheduled
2012/09/28 07:13:26 OSPF: SPF: calculation timer is already scheduled: 0x8132850
2012/09/28 07:13:26 OSPF: LSA[Type3:10.157.96.0]: 0x81e5fb0 already exists on MaxAge LSA list
2012/09/28 07:13:26 OSPF: SPF: Timer (SPF calculation expire)
2012/09/28 07:13:26 OSPF: ospf_spf_calculate: Start
2012/09/28 07:13:26 OSPF: ospf_spf_calculate: running Dijkstra for area 0.0.0.1
2012/09/28 07:13:26 OSPF: ospf_vertex_new: Created Router vertex 10.157.91.40
2012/09/28 07:13:26 OSPF: ospf_spf_next: Next vertex of Router vertex 10.157.91.40
2012/09/28 07:13:26 OSPF: Looking up Network LSA, ID: 10.157.91.40
2012/09/28 07:13:26 OSPF: found the LSA
2012/09/28 07:13:26 OSPF: ospf_vertex_new: Created Network vertex 10.157.91.40
2012/09/28 07:13:26 OSPF: ospf_nexthop_calculation(): Start
2012/09/28 07:13:26 OSPF: V (parent): Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:26 OSPF: W (dest)  : Network vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:26 OSPF: V->W distance: 10
2012/09/28 07:13:26 OSPF: ospf_spf_add_parent: Adding 10.157.91.40 as parent of 10.157.91.40
2012/09/28 07:13:26 OSPF: ospf_spf_next: Next vertex of Network vertex 10.157.91.40
2012/09/28 07:13:26 OSPF: found Router LSA 10.157.91.30
2012/09/28 07:13:26 OSPF: ospf_vertex_new: Created Router vertex 10.157.91.30
2012/09/28 07:13:26 OSPF: ospf_nexthop_calculation(): Start
2012/09/28 07:13:26 OSPF: V (parent): Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:26 OSPF: parent 10.157.91.40 backlink 1 nexthop 0.0.0.0  interface em1:10.157.91.40
2012/09/28 07:13:26 OSPF: W (dest)  : Router vertex 10.157.91.30  distance 0 flags 0
2012/09/28 07:13:26 OSPF: V->W distance: 10
2012/09/28 07:13:26 OSPF: ospf_spf_add_parent: Adding 10.157.91.40 as parent of 10.157.91.30
2012/09/28 07:13:26 OSPF: found Router LSA 10.157.91.40
2012/09/28 07:13:26 OSPF: The LSA is already in SPF
2012/09/28 07:13:26 OSPF: ospf_intra_add_router: Start
2012/09/28 07:13:26 OSPF: ospf_intra_add_router: LS ID: 10.157.91.30
2012/09/28 07:13:26 OSPF: ospf_vl_up_check(): Start
2012/09/28 07:13:26 OSPF: ospf_vl_up_check(): Router ID is 10.157.91.30
2012/09/28 07:13:26 OSPF: ospf_vl_up_check(): Area is 0.0.0.1
2012/09/28 07:13:26 OSPF: ospf_intra_add_router: talking about 10.157.91.30/32
2012/09/28 07:13:26 OSPF: ospf_intra_add_router: Stop
2012/09/28 07:13:26 OSPF: ospf_spf_next: Next vertex of Router vertex 10.157.91.30
2012/09/28 07:13:26 OSPF: Looking up Network LSA, ID: 10.157.91.40
2012/09/28 07:13:26 OSPF: found the LSA
2012/09/28 07:13:26 OSPF: The LSA is already in SPF
2012/09/28 07:13:26 OSPF: SPF Result: 0 [R] 10.157.91.40
2012/09/28 07:13:26 OSPF: SPF Result: 1 [N] 10.157.91.40/24
2012/09/28 07:13:26 OSPF:  nexthop 0x820ce60 0.0.0.0 em1:10.157.91.40
2012/09/28 07:13:26 OSPF: SPF Result: 2 [R] 10.157.91.30
2012/09/28 07:13:26 OSPF:  nexthop 0x820f0e0 10.157.91.30 em1:10.157.91.40
2012/09/28 07:13:26 OSPF: ========== OSPF routing table ==========
2012/09/28 07:13:26 OSPF: N 10.157.91.0/24	0.0.0.1	intra-area	10
2012/09/28 07:13:26 OSPF:   -> 0.0.0.0
2012/09/28 07:13:26 OSPF: ========================================
2012/09/28 07:13:26 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:26 OSPF: ospf_process_stubs():processing router LSA, id: 10.157.91.40
2012/09/28 07:13:26 OSPF: ospf_process_stubs(): we have 1 links to process
2012/09/28 07:13:26 OSPF: ospf_process_stubs(): after examining links:  Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:26 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:26 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:26 OSPF: ospf_process_stubs(): after examining links:  Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:26 OSPF: parent 10.157.91.40 backlink 1 nexthop 0.0.0.0  interface em1:10.157.91.40
2012/09/28 07:13:26 OSPF:  child: Router vertex 10.157.91.30  distance 10 flags 0
2012/09/28 07:13:26 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:26 OSPF: ospf_process_stubs():processing router LSA, id: 10.157.91.30
2012/09/28 07:13:26 OSPF: ospf_process_stubs(): we have 1 links to process
2012/09/28 07:13:26 OSPF: ospf_process_stubs(): after examining links:  Router vertex 10.157.91.30  distance 10 flags 0
2012/09/28 07:13:26 OSPF: parent 10.157.91.40 backlink 0 nexthop 10.157.91.30  interface em1:10.157.91.40
2012/09/28 07:13:26 OSPF: ospf_spf_calculate Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:26 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 1
2012/09/28 07:13:26 OSPF: ospf_vertex_free: Free Router vertex 10.157.91.40
2012/09/28 07:13:26 OSPF: ospf_vertex_free: Free Network vertex 10.157.91.40
2012/09/28 07:13:26 OSPF: ospf_vertex_free: Free Router vertex 10.157.91.30
2012/09/28 07:13:26 OSPF: ospf_spf_calculate: Stop. 0 vertices
2012/09/28 07:13:26 OSPF: ospf_ia_routing():start
2012/09/28 07:13:26 OSPF: ospf_ia_routing():not ABR, considering all areas
2012/09/28 07:13:26 OSPF: process_summary_lsa(): LS ID: 10.157.92.0
2012/09/28 07:13:26 OSPF: ospf_ia_network_route(): processing summary route to 10.157.92.0/24
2012/09/28 07:13:26 OSPF: ospf_ia_network_route(): add new route to 10.157.92.0/24
2012/09/28 07:13:26 OSPF: process_summary_lsa(): LS ID: 10.157.96.0
2012/09/28 07:13:26 OSPF: Pruning unreachable networks
2012/09/28 07:13:26 OSPF: Pruning unreachable routers
2012/09/28 07:13:26 OSPF: Route: Router Routing Table free
2012/09/28 07:13:26 OSPF: SPF: calculation complete
2012/09/28 07:13:27 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:13:27 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 159
2012/09/28 07:13:27 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 158
2012/09/28 07:13:27 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:13:27 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:13:27 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:13:28 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:13:28 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:28 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:13:37 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:13:37 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 160
2012/09/28 07:13:37 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 159
2012/09/28 07:13:37 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:13:37 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:13:37 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:13:38 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:13:38 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:38 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:13:47 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:13:47 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 161
2012/09/28 07:13:47 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 160
2012/09/28 07:13:47 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:13:47 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:13:47 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:13:48 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:13:48 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:48 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:13:56 OSPF: LSA[Type2,id(10.157.91.30),ar(10.157.91.30)]: is MaxAge
2012/09/28 07:13:56 OSPF: SPF: calculation timer scheduled
2012/09/28 07:13:56 OSPF: SPF: calculation timer delay = 200
2012/09/28 07:13:56 OSPF: LSA[Type2:10.157.91.30]: 0x81e5e90 already exists on MaxAge LSA list
2012/09/28 07:13:56 OSPF: LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]: is MaxAge
2012/09/28 07:13:56 OSPF: SPF: calculation timer scheduled
2012/09/28 07:13:56 OSPF: SPF: calculation timer is already scheduled: 0x8132cd0
2012/09/28 07:13:56 OSPF: LSA[Type3:10.157.96.0]: 0x81e5fb0 already exists on MaxAge LSA list
2012/09/28 07:13:56 OSPF: SPF: Timer (SPF calculation expire)
2012/09/28 07:13:56 OSPF: ospf_spf_calculate: Start
2012/09/28 07:13:56 OSPF: ospf_spf_calculate: running Dijkstra for area 0.0.0.1
2012/09/28 07:13:56 OSPF: ospf_vertex_new: Created Router vertex 10.157.91.40
2012/09/28 07:13:56 OSPF: ospf_spf_next: Next vertex of Router vertex 10.157.91.40
2012/09/28 07:13:56 OSPF: Looking up Network LSA, ID: 10.157.91.40
2012/09/28 07:13:56 OSPF: found the LSA
2012/09/28 07:13:56 OSPF: ospf_vertex_new: Created Network vertex 10.157.91.40
2012/09/28 07:13:56 OSPF: ospf_nexthop_calculation(): Start
2012/09/28 07:13:56 OSPF: V (parent): Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:56 OSPF: W (dest)  : Network vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:56 OSPF: V->W distance: 10
2012/09/28 07:13:56 OSPF: ospf_spf_add_parent: Adding 10.157.91.40 as parent of 10.157.91.40
2012/09/28 07:13:56 OSPF: ospf_spf_next: Next vertex of Network vertex 10.157.91.40
2012/09/28 07:13:56 OSPF: found Router LSA 10.157.91.30
2012/09/28 07:13:56 OSPF: ospf_vertex_new: Created Router vertex 10.157.91.30
2012/09/28 07:13:56 OSPF: ospf_nexthop_calculation(): Start
2012/09/28 07:13:56 OSPF: V (parent): Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:56 OSPF: parent 10.157.91.40 backlink 1 nexthop 0.0.0.0  interface em1:10.157.91.40
2012/09/28 07:13:56 OSPF: W (dest)  : Router vertex 10.157.91.30  distance 0 flags 0
2012/09/28 07:13:56 OSPF: V->W distance: 10
2012/09/28 07:13:56 OSPF: ospf_spf_add_parent: Adding 10.157.91.40 as parent of 10.157.91.30
2012/09/28 07:13:56 OSPF: found Router LSA 10.157.91.40
2012/09/28 07:13:56 OSPF: The LSA is already in SPF
2012/09/28 07:13:56 OSPF: ospf_intra_add_router: Start
2012/09/28 07:13:56 OSPF: ospf_intra_add_router: LS ID: 10.157.91.30
2012/09/28 07:13:56 OSPF: ospf_vl_up_check(): Start
2012/09/28 07:13:56 OSPF: ospf_vl_up_check(): Router ID is 10.157.91.30
2012/09/28 07:13:56 OSPF: ospf_vl_up_check(): Area is 0.0.0.1
2012/09/28 07:13:56 OSPF: ospf_intra_add_router: talking about 10.157.91.30/32
2012/09/28 07:13:56 OSPF: ospf_intra_add_router: Stop
2012/09/28 07:13:56 OSPF: ospf_spf_next: Next vertex of Router vertex 10.157.91.30
2012/09/28 07:13:56 OSPF: Looking up Network LSA, ID: 10.157.91.40
2012/09/28 07:13:56 OSPF: found the LSA
2012/09/28 07:13:56 OSPF: The LSA is already in SPF
2012/09/28 07:13:56 OSPF: SPF Result: 0 [R] 10.157.91.40
2012/09/28 07:13:56 OSPF: SPF Result: 1 [N] 10.157.91.40/24
2012/09/28 07:13:56 OSPF:  nexthop 0x820cec0 0.0.0.0 em1:10.157.91.40
2012/09/28 07:13:56 OSPF: SPF Result: 2 [R] 10.157.91.30
2012/09/28 07:13:56 OSPF:  nexthop 0x820f140 10.157.91.30 em1:10.157.91.40
2012/09/28 07:13:56 OSPF: ========== OSPF routing table ==========
2012/09/28 07:13:56 OSPF: N 10.157.91.0/24	0.0.0.1	intra-area	10
2012/09/28 07:13:56 OSPF:   -> 0.0.0.0
2012/09/28 07:13:56 OSPF: ========================================
2012/09/28 07:13:56 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:56 OSPF: ospf_process_stubs():processing router LSA, id: 10.157.91.40
2012/09/28 07:13:56 OSPF: ospf_process_stubs(): we have 1 links to process
2012/09/28 07:13:56 OSPF: ospf_process_stubs(): after examining links:  Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:56 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:56 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:56 OSPF: ospf_process_stubs(): after examining links:  Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 07:13:56 OSPF: parent 10.157.91.40 backlink 1 nexthop 0.0.0.0  interface em1:10.157.91.40
2012/09/28 07:13:56 OSPF:  child: Router vertex 10.157.91.30  distance 10 flags 0
2012/09/28 07:13:56 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 07:13:56 OSPF: ospf_process_stubs():processing router LSA, id: 10.157.91.30
2012/09/28 07:13:56 OSPF: ospf_process_stubs(): we have 1 links to process
2012/09/28 07:13:56 OSPF: ospf_process_stubs(): after examining links:  Router vertex 10.157.91.30  distance 10 flags 0
2012/09/28 07:13:56 OSPF: parent 10.157.91.40 backlink 0 nexthop 10.157.91.30  interface em1:10.157.91.40
2012/09/28 07:13:56 OSPF: ospf_spf_calculate Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 07:13:56 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 1
2012/09/28 07:13:56 OSPF: ospf_vertex_free: Free Router vertex 10.157.91.40
2012/09/28 07:13:56 OSPF: ospf_vertex_free: Free Network vertex 10.157.91.40
2012/09/28 07:13:56 OSPF: ospf_vertex_free: Free Router vertex 10.157.91.30
2012/09/28 07:13:56 OSPF: ospf_spf_calculate: Stop. 0 vertices
2012/09/28 07:13:56 OSPF: ospf_ia_routing():start
2012/09/28 07:13:56 OSPF: ospf_ia_routing():not ABR, considering all areas
2012/09/28 07:13:56 OSPF: process_summary_lsa(): LS ID: 10.157.92.0
2012/09/28 07:13:56 OSPF: ospf_ia_network_route(): processing summary route to 10.157.92.0/24
2012/09/28 07:13:56 OSPF: ospf_ia_network_route(): add new route to 10.157.92.0/24
2012/09/28 07:13:56 OSPF: process_summary_lsa(): LS ID: 10.157.96.0
2012/09/28 07:13:56 OSPF: Pruning unreachable networks
2012/09/28 07:13:56 OSPF: Pruning unreachable routers
2012/09/28 07:13:56 OSPF: Route: Router Routing Table free
2012/09/28 07:13:56 OSPF: SPF: calculation complete
2012/09/28 07:13:57 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:13:57 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 162
2012/09/28 07:13:57 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 161
2012/09/28 07:13:57 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:13:57 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:13:57 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:13:58 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:13:58 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:13:58 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:14:04 OSPF: Vty connection from 127.0.0.1
2012/09/28 07:14:07 OSPF: LSA[MaxAge]: remover Start
2012/09/28 07:14:07 OSPF: LSA[Type2:10.157.91.30]: MaxAge LSA removed from list
2012/09/28 07:14:07 OSPF: LSA: freed 0x81e5e90
2012/09/28 07:14:07 OSPF: LSA[Type2:10.157.91.30]: data freed 0x820de50
2012/09/28 07:14:07 OSPF: LSA[Type3:10.157.96.0]: MaxAge LSA removed from list
2012/09/28 07:14:07 OSPF: LSA: freed 0x81e5fb0
2012/09/28 07:14:07 OSPF: LSA[Type3:10.157.96.0]: data freed 0x820d9d0
2012/09/28 07:14:07 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:14:07 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 163
2012/09/28 07:14:07 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 162
2012/09/28 07:14:07 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:14:07 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:14:07 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:14:08 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:14:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:14:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:14:17 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:14:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 164
2012/09/28 07:14:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 163
2012/09/28 07:14:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:14:17 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:14:17 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:14:18 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:14:18 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:14:18 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:14:27 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:14:27 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 165
2012/09/28 07:14:27 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 164
2012/09/28 07:14:27 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:14:27 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:14:27 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:14:28 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:14:28 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:14:28 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:14:37 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:14:37 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 166
2012/09/28 07:14:37 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 165
2012/09/28 07:14:37 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:14:37 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:14:37 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:14:38 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:14:38 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:14:38 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:14:47 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:14:47 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 167
2012/09/28 07:14:47 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 166
2012/09/28 07:14:47 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:14:47 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:14:47 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:14:48 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:14:48 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:14:48 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:14:57 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:14:57 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 168
2012/09/28 07:14:57 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 167
2012/09/28 07:14:57 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:14:57 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:14:57 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:14:58 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:14:58 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:14:58 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:15:07 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:15:07 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 169
2012/09/28 07:15:07 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 168
2012/09/28 07:15:07 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:15:07 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:15:07 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:15:08 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:15:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:15:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:15:17 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:15:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 170
2012/09/28 07:15:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 169
2012/09/28 07:15:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:15:17 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:15:17 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:15:18 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:15:18 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:15:18 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:15:27 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:15:27 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 171
2012/09/28 07:15:27 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 170
2012/09/28 07:15:27 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:15:27 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:15:27 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:15:28 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:15:28 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:15:28 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:15:37 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:15:37 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 172
2012/09/28 07:15:37 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 171
2012/09/28 07:15:37 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:15:37 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:15:37 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:15:38 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:15:38 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:15:38 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:15:47 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:15:47 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 173
2012/09/28 07:15:47 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 172
2012/09/28 07:15:47 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:15:47 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:15:47 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:15:48 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:15:48 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:15:48 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:15:57 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:15:57 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 174
2012/09/28 07:15:57 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 173
2012/09/28 07:15:57 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:15:57 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:15:57 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:15:58 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:15:58 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:15:58 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:16:07 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:16:07 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 175
2012/09/28 07:16:07 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 174
2012/09/28 07:16:07 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:16:07 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:16:07 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:16:08 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:16:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketReceived)
2012/09/28 07:16:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (2-WayReceived)
2012/09/28 07:16:17 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 07:16:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 176
2012/09/28 07:16:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 175
2012/09/28 07:16:17 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 07:16:17 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 07:16:17 OSPF: make_hello: options: 2, int: em1:10.157.91.40
2012/09/28 07:16:18 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 07:16:18 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Full (PacketRecei
-------------- next part --------------
2012/09/28 08:03:59 OSPF: Packet 10.157.91.30 [Hello:RECV]: Options *|-|-|-|-|-|E|*
2012/09/28 08:03:59 OSPF: NSM[em1:10.157.91.40:0.0.0.0]: start
2012/09/28 08:03:59 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Down (PacketReceived)
2012/09/28 08:03:59 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: State change Down -> Init (PacketReceived)


2012/09/28 08:03:59 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Init (1-WayReceived)
2012/09/28 08:04:08 OSPF: LSA[Refresh]:ospf_lsa_refresh_walker(): start
2012/09/28 08:04:08 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): next index 192
2012/09/28 08:04:08 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): refresh index 191
2012/09/28 08:04:08 OSPF: LSA[Refresh]: ospf_lsa_refresh_walker(): end
2012/09/28 08:04:08 OSPF: ISM[em1:10.157.91.40]: Timer (Hello timer expire)
2012/09/28 08:04:08 OSPF: make_hello: options: 2, int: em1:10.157.91.40


2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Init (2-WayReceived)
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: State change Init -> ExStart (2-WayReceived)
2012/09/28 08:04:08 OSPF: DR-Election[1st]: Backup 10.157.91.30
2012/09/28 08:04:08 OSPF: DR-Election[1st]: DR     10.157.91.40
2012/09/28 08:04:08 OSPF: ISM[em1:10.157.91.40]: DR (NeighborChange)
2012/09/28 08:04:08 OSPF: Packet[DD]: Neighbor 10.157.91.30: Initial DBD from Slave, ignoring.
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: ExStart (PacketReceived)
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: ExStart (AdjOK?)
2012/09/28 08:04:08 OSPF: Packet[DD]: Neighbor 10.157.91.30 Negotiation done (Master).
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: ExStart (NegotiationDone)
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: State change ExStart -> Exchange (NegotiationDone)
2012/09/28 08:04:08 OSPF: Packet [DD:RECV]: LSA received Type 1, ID 10.157.91.30 is not recent.
2012/09/28 08:04:08 OSPF: LSA: freed 0x820e470
2012/09/28 08:04:08 OSPF: LSA[Type1:10.157.91.30]: data freed 0x820de90
2012/09/28 08:04:08 OSPF: Packet [DD:RECV]: LSA received Type 3, ID 10.157.92.0 is not recent.
2012/09/28 08:04:08 OSPF: LSA: freed 0x820e470
2012/09/28 08:04:08 OSPF: LSA[Type3:10.157.92.0]: data freed 0x820de90
2012/09/28 08:04:08 OSPF: LSA[Type3:10.157.96.0]: Lookup by header, NO MATCH
2012/09/28 08:04:08 OSPF: RqstL(0)++, NBR(10.157.91.30), LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Exchange (PacketReceived)
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Exchange (PacketReceived)
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Exchange (ExchangeDone)
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: State change Exchange -> Loading (ExchangeDone)
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Loading (PacketReceived)
2012/09/28 08:04:08 OSPF: ospf_ls_upd_send_queue start
2012/09/28 08:04:08 OSPF: listcount = 3, dst 224.0.0.5
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: Start
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: Stop
2012/09/28 08:04:08 OSPF: ospf_ls_upd_send_queue stop
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Loading (PacketReceived)
2012/09/28 08:04:08 OSPF: ospf_ls_upd_send_queue start
2012/09/28 08:04:08 OSPF: listcount = 1, dst 224.0.0.5
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: Start
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: Stop
2012/09/28 08:04:08 OSPF: ospf_ls_upd_send_queue stop
2012/09/28 08:04:08 OSPF: LSA[Type3:10.157.96.0]: 0x820e230 new LSA created with Link State Update
2012/09/28 08:04:08 OSPF: LSA[Type3:10.157.96.0]: Lookup by header, NO MATCH
2012/09/28 08:04:08 OSPF: LSA[Flooding]: start, NBR 10.157.91.30 (Loading), cur(0x0), New-LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]
2012/09/28 08:04:08 OSPF: ospf_flood_through_interface(): considering int em1:10.157.91.40, INBR(10.157.91.30), LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]
2012/09/28 08:04:08 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.30 (Loading)
2012/09/28 08:04:08 OSPF: ospf_flood_through_interface(): nbr adj is not Full
2012/09/28 08:04:08 OSPF: RqstL(1)--, NBR(10.157.91.30), LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]
2012/09/28 08:04:08 OSPF: LSA: freed 0x820e470
2012/09/28 08:04:08 OSPF: LSA[Type3:10.157.96.0]: data freed 0x820de90
2012/09/28 08:04:08 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.40 (2-Way)
2012/09/28 08:04:08 OSPF: SPF: calculation timer scheduled
2012/09/28 08:04:08 OSPF: SPF: calculation timer delay = 200
2012/09/28 08:04:08 OSPF: ospf_summary_lsa_install(): SPF scheduled
2012/09/28 08:04:08 OSPF: LSA[Type3,id(10.157.96.0),ar(10.157.91.30)]: Install summary-LSA to Area 0.0.0.1
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Loading (PacketReceived)
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: Loading (LoadingDone)
2012/09/28 08:04:08 OSPF: NSM[em1:10.157.91.40:10.157.91.30]: State change Loading -> Full (LoadingDone)
2012/09/28 08:04:08 OSPF: ospf_check_abr_status(): Start
2012/09/28 08:04:08 OSPF: ospf_check_abr_status(): looked through areas
2012/09/28 08:04:08 OSPF: ospf_check_abr_status(): bb_configured: 0
2012/09/28 08:04:08 OSPF: ospf_check_abr_status(): bb_act_attached: 0
2012/09/28 08:04:08 OSPF: ospf_check_abr_status(): areas_configured: 1
2012/09/28 08:04:08 OSPF: ospf_check_abr_status(): areas_act_attached: 1
2012/09/28 08:04:08 OSPF: nsm_change_state(10.157.91.30, Loading -> Full): scheduling new router-LSA origination
2012/09/28 08:04:08 OSPF: [router-LSA]: (router-LSA area update)
2012/09/28 08:04:08 OSPF: LSA[Type1]: Create router-LSA instance
2012/09/28 08:04:08 OSPF: counting fully adjacent virtual neighbors in area 0.0.0.1
2012/09/28 08:04:08 OSPF: there are 0 of them
2012/09/28 08:04:08 OSPF: LSA: freed 0x81e5cb0
2012/09/28 08:04:08 OSPF: LSA[Type1:10.157.91.40]: data freed 0x8203e30
2012/09/28 08:04:08 OSPF: LSA[Refresh]: lsa 10.157.91.40 with age 0 added to index 101
2012/09/28 08:04:08 OSPF: LSA[Refresh:10.157.91.40]: ospf_refresher_register_lsa(): setting refresh_list on lsa 0x820e350 (slod 101)
2012/09/28 08:04:08 OSPF: SPF: calculation timer scheduled
2012/09/28 08:04:08 OSPF: SPF: calculation timer is already scheduled: 0x8132cd0
2012/09/28 08:04:08 OSPF: LSA[Type1,id(10.157.91.40),ar(10.157.91.40)]: Install router-LSA to Area 0.0.0.1
2012/09/28 08:04:08 OSPF: ospf_flood_through_interface(): considering int em1:10.157.91.40, INBR(NULL), LSA[Type1,id(10.157.91.40),ar(10.157.91.40)]
2012/09/28 08:04:08 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.30 (Full)
2012/09/28 08:04:08 OSPF: RXmtL(0)++, NBR(10.157.91.30), LSA[Type1,id(10.157.91.40),ar(10.157.91.40)]
2012/09/28 08:04:08 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.40 (2-Way)
2012/09/28 08:04:08 OSPF: LSA[Type1:10.157.91.40]: router-LSA refresh
2012/09/28 08:04:08 OSPF:   LSA Header
2012/09/28 08:04:08 OSPF:     LS age 0
2012/09/28 08:04:08 OSPF:     Options 2 (*|-|-|-|-|-|E|*)
2012/09/28 08:04:08 OSPF:     LS type 1 (router-LSA)
2012/09/28 08:04:08 OSPF:     Link State ID 10.157.91.40
2012/09/28 08:04:08 OSPF:     Advertising Router 10.157.91.40
2012/09/28 08:04:08 OSPF:     LS sequence number 0x80000093
2012/09/28 08:04:08 OSPF:     LS checksum 0xd139
2012/09/28 08:04:08 OSPF:     length 36
2012/09/28 08:04:08 OSPF: LSA[Type2]: Create network-LSA instance
2012/09/28 08:04:08 OSPF: LSA[Refresh]: lsa 10.157.91.40 with age 0 added to index 95
2012/09/28 08:04:08 OSPF: LSA[Refresh:10.157.91.40]: ospf_refresher_register_lsa(): setting refresh_list on lsa 0x820e470 (slod 95)
2012/09/28 08:04:08 OSPF: SPF: calculation timer scheduled
2012/09/28 08:04:08 OSPF: SPF: calculation timer is already scheduled: 0x8132cd0
2012/09/28 08:04:08 OSPF: LSA[Type2,id(10.157.91.40),ar(10.157.91.40)]: Install network-LSA to Area 0.0.0.1
2012/09/28 08:04:08 OSPF: ospf_flood_through_interface(): considering int em1:10.157.91.40, INBR(NULL), LSA[Type2,id(10.157.91.40),ar(10.157.91.40)]
2012/09/28 08:04:08 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.30 (Full)
2012/09/28 08:04:08 OSPF: RXmtL(1)++, NBR(10.157.91.30), LSA[Type2,id(10.157.91.40),ar(10.157.91.40)]
2012/09/28 08:04:08 OSPF: ospf_flood_through_interface(): considering nbr 10.157.91.40 (2-Way)
2012/09/28 08:04:08 OSPF: LSA[Type2:10.157.91.40]: Originate network-LSA 0x820e470
2012/09/28 08:04:08 OSPF:   LSA Header
2012/09/28 08:04:08 OSPF:     LS age 0
2012/09/28 08:04:08 OSPF:     Options 2 (*|-|-|-|-|-|E|*)
2012/09/28 08:04:08 OSPF:     LS type 2 (network-LSA)
2012/09/28 08:04:08 OSPF:     Link State ID 10.157.91.40
2012/09/28 08:04:08 OSPF:     Advertising Router 10.157.91.40
2012/09/28 08:04:08 OSPF:     LS sequence number 0x80000002
2012/09/28 08:04:08 OSPF:     LS checksum 0xa115
2012/09/28 08:04:08 OSPF:     length 32
2012/09/28 08:04:08 OSPF: ospf_ls_upd_send_queue start
2012/09/28 08:04:08 OSPF: listcount = 2, dst 224.0.0.5
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: Start
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: List Iteration
2012/09/28 08:04:08 OSPF: ospf_make_ls_upd: Stop
2012/09/28 08:04:08 OSPF: ospf_ls_upd_send_queue stop
2012/09/28 08:04:08 OSPF: SPF: Timer (SPF calculation expire)
2012/09/28 08:04:08 OSPF: ospf_spf_calculate: Start
2012/09/28 08:04:08 OSPF: ospf_spf_calculate: running Dijkstra for area 0.0.0.1
2012/09/28 08:04:08 OSPF: ospf_vertex_new: Created Router vertex 10.157.91.40
2012/09/28 08:04:08 OSPF: ospf_spf_next: Next vertex of Router vertex 10.157.91.40
2012/09/28 08:04:08 OSPF: Looking up Network LSA, ID: 10.157.91.40
2012/09/28 08:04:08 OSPF: found the LSA
2012/09/28 08:04:08 OSPF: ospf_vertex_new: Created Network vertex 10.157.91.40
2012/09/28 08:04:08 OSPF: ospf_nexthop_calculation(): Start
2012/09/28 08:04:08 OSPF: V (parent): Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 08:04:08 OSPF: W (dest)  : Network vertex 10.157.91.40  distance 0 flags 0
2012/09/28 08:04:08 OSPF: V->W distance: 10
2012/09/28 08:04:08 OSPF: ospf_spf_add_parent: Adding 10.157.91.40 as parent of 10.157.91.40
2012/09/28 08:04:08 OSPF: ospf_spf_next: Next vertex of Network vertex 10.157.91.40
2012/09/28 08:04:08 OSPF: found Router LSA 10.157.91.30
2012/09/28 08:04:08 OSPF: ospf_vertex_new: Created Router vertex 10.157.91.30
2012/09/28 08:04:08 OSPF: ospf_nexthop_calculation(): Start
2012/09/28 08:04:08 OSPF: V (parent): Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 08:04:08 OSPF: parent 10.157.91.40 backlink 1 nexthop 0.0.0.0  interface em1:10.157.91.40
2012/09/28 08:04:08 OSPF: W (dest)  : Router vertex 10.157.91.30  distance 0 flags 0
2012/09/28 08:04:08 OSPF: V->W distance: 10
2012/09/28 08:04:08 OSPF: ospf_spf_add_parent: Adding 10.157.91.40 as parent of 10.157.91.30
2012/09/28 08:04:08 OSPF: found Router LSA 10.157.91.40
2012/09/28 08:04:08 OSPF: The LSA is already in SPF
2012/09/28 08:04:08 OSPF: ospf_intra_add_router: Start
2012/09/28 08:04:08 OSPF: ospf_intra_add_router: LS ID: 10.157.91.30
2012/09/28 08:04:08 OSPF: ospf_vl_up_check(): Start
2012/09/28 08:04:08 OSPF: ospf_vl_up_check(): Router ID is 10.157.91.30
2012/09/28 08:04:08 OSPF: ospf_vl_up_check(): Area is 0.0.0.1
2012/09/28 08:04:08 OSPF: ospf_intra_add_router: talking about 10.157.91.30/32
2012/09/28 08:04:08 OSPF: ospf_intra_add_router: Stop
2012/09/28 08:04:08 OSPF: ospf_spf_next: Next vertex of Router vertex 10.157.91.30
2012/09/28 08:04:08 OSPF: Looking up Network LSA, ID: 10.157.91.40
2012/09/28 08:04:08 OSPF: found the LSA
2012/09/28 08:04:08 OSPF: The LSA is already in SPF
2012/09/28 08:04:08 OSPF: SPF Result: 0 [R] 10.157.91.40
2012/09/28 08:04:08 OSPF: SPF Result: 1 [N] 10.157.91.40/24
2012/09/28 08:04:08 OSPF:  nexthop 0x820cfe0 0.0.0.0 em1:10.157.91.40
2012/09/28 08:04:08 OSPF: SPF Result: 2 [R] 10.157.91.30
2012/09/28 08:04:08 OSPF:  nexthop 0x820f230 10.157.91.30 em1:10.157.91.40
2012/09/28 08:04:08 OSPF: ========== OSPF routing table ==========
2012/09/28 08:04:08 OSPF: N 10.157.91.0/24	0.0.0.1	intra-area	10
2012/09/28 08:04:08 OSPF:   -> 0.0.0.0
2012/09/28 08:04:08 OSPF: ========================================
2012/09/28 08:04:08 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 08:04:08 OSPF: ospf_process_stubs():processing router LSA, id: 10.157.91.40
2012/09/28 08:04:08 OSPF: ospf_process_stubs(): we have 1 links to process
2012/09/28 08:04:08 OSPF: ospf_process_stubs(): after examining links:  Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 08:04:08 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 08:04:08 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 08:04:08 OSPF: ospf_process_stubs(): after examining links:  Network vertex 10.157.91.40  distance 10 flags 0
2012/09/28 08:04:08 OSPF: parent 10.157.91.40 backlink 1 nexthop 0.0.0.0  interface em1:10.157.91.40
2012/09/28 08:04:08 OSPF:  child: Router vertex 10.157.91.30  distance 10 flags 0
2012/09/28 08:04:08 OSPF: ospf_process_stub():processing stubs for area 0.0.0.1
2012/09/28 08:04:08 OSPF: ospf_process_stubs():processing router LSA, id: 10.157.91.30
2012/09/28 08:04:08 OSPF: ospf_process_stubs(): we have 1 links to process
2012/09/28 08:04:08 OSPF: ospf_process_stubs(): after examining links:  Router vertex 10.157.91.30  distance 10 flags 0
2012/09/28 08:04:08 OSPF: parent 10.157.91.40 backlink 0 nexthop 10.157.91.30  interface em1:10.157.91.40
2012/09/28 08:04:08 OSPF: ospf_spf_calculate Router vertex 10.157.91.40  distance 0 flags 0
2012/09/28 08:04:08 OSPF:  child: Network vertex 10.157.91.40  distance 10 flags 1
2012/09/28 08:04:08 OSPF: ospf_vertex_free: Free Router vertex 10.157.91.40
2012/09/28 08:04:08 OSPF: ospf_vertex_free: Free Network vertex 10.157.91.40
2012/09/28 08:04:08 OSPF: ospf_vertex_free: Free Router vertex 10.157.91.30
2012/09/28 08:04:08 OSPF: ospf_spf_calculate: Stop. 0 vertices
2012/09/28 08:04:08 OSPF: ospf_ia_routing():start
2012/09/28 08:04:08 OSPF: ospf_ia_routing():not ABR, considering all areas
2012/09/28 08:04:08 OSPF: process_summary_lsa(): LS ID: 10.157.92.0
2012/09/28 08:04:08 OSPF: ospf_ia_network_route(): processing summary route to 10.157.92.0/24
2012/09/28 08:04:08 OSPF: ospf_ia_network_route(): add new route to 10.157.92.0/24




<learning 10.157.96.0 network working fine>

2012/09/28 07:13:07 OSPF: process_summary_lsa(): LS ID: 10.157.96.0
2012/09/28 08:04:08 OSPF: process_summary_lsa(): LS ID: 10.157.96.0
2012/09/28 08:04:08 OSPF: ospf_ia_network_route(): processing summary route to 10.157.96.0/24
2012/09/28 08:04:08 OSPF: ospf_ia_network_route(): add new route to 10.157.96.0/24
2012/09/28 08:04:08 OSPF: Pruning unreachable networks
2012/09/28 08:04:08 OSPF: Pruning unreachable routers

2012/09/28 08:04:08 OSPF: Zebra: Route add 10.157.92.0/24 nexthop 10.157.91.30
2012/09/28 08:04:08 OSPF: Zebra: Route add 10.157.96.0/24 nexthop 10.157.91.30
2012/09/28 08:04:08 OSPF: Route: Router Routing Table free
2012/09/28 08:04:08 OSPF: SPF: calculation complete
-------------- next part --------------
A non-text attachment was scrubbed...
Name: setup.pptx
Type: application/vnd.openxmlformats-officedocument.presentationml.presentation
Size: 47445 bytes
Desc: not available
URL: <http://lists.quagga.net/pipermail/quagga-users/attachments/20120929/b2b99978/attachment-0001.pptx>


More information about the Quagga-users mailing list