RP/0/RP0/CPU0:R5#show ospf trace spf_ext Sun Sep 13 15:49:51.832 UTC Traces for OSPF 1 (Thu Jan 1 00:00:00) Traces returned/requested/available: 0/256/0 Trace buffer: spf_ext No trace entries are available RP/0/RP0/CPU0:R5#show ospf trace adj_cycle Sun Sep 13 15:49:52.523 UTC Traces for OSPF 1 (Sun Sep 13 15:49:53) Traces returned/requested/available: 67/8192/67 Trace buffer: adj_cycle 1 Sep 13 14:58:11.276 ospf_send_dbd: to 3.3.3.3(10.2.35.3) intf Gi0/0/0/0 seq 0x64749b1a 2 Sep 13 14:58:11.288 ospf_rcv_dbd: from 3.3.3.3(10.2.35.3) intf Gi0/0/0/0 seq 0x4d4d2fa 3 Sep 13 14:58:11.288 ospf_rcv_dbd: from 3.3.3.3(10.2.35.3) intf Gi0/0/0/0 seq 0x64749b1a 4 Sep 13 14:58:11.288 ospf_send_dbd: to 3.3.3.3(10.2.35.3) intf Gi0/0/0/0 seq 0x64749b1b 5 Sep 13 14:58:11.294 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 3 paksize 126 6 Sep 13 14:58:11.295 ospf_output: LSA pkt, src 10.2.35.5 dest 10.2.35.3 area 0.0.0.2 intf Gi0/0/0/0 7 Sep 13 14:58:11.295 ospf_rcv_dbd: from 3.3.3.3(10.2.35.3) intf Gi0/0/0/0 seq 0x64749b1b 8 Sep 13 14:58:11.295 ospf_output: LSA pkt, src 10.2.35.5 dest 10.2.35.3 area 0.0.0.2 intf Gi0/0/0/0 9 Sep 13 14:58:11.295 ospf_send_req: LS REQ pkt, nbr 3.3.3.3/10.2.35.3 cnt 5 unsent 0 10 Sep 13 14:58:11.302 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 4 paksize 266 11 Sep 13 14:58:11.374 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 12 Sep 13 14:58:11.375 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 4 paksize 166 13 Sep 13 14:58:11.583 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 4 paksize 202 14 Sep 13 14:58:12.878 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 4 paksize 146 15 Sep 13 14:58:13.302 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 5 paksize 134 16 Sep 13 14:58:13.306 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 17 Sep 13 14:58:16.693 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 4 paksize 146 18 Sep 13 14:58:18.693 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 19 Sep 13 14:58:45.976 ospf_send_dbd: to 6.6.6.6(10.2.0.6) intf Gi0/0/0/1 seq 0x9ad4330 20 Sep 13 14:58:50.771 ospf_send_dbd: to 6.6.6.6(10.2.0.6) intf Gi0/0/0/1 seq 0x9ad4330 21 Sep 13 14:58:51.320 ospf_rcv_dbd: from 6.6.6.6(10.2.0.6) intf Gi0/0/0/1 seq 0x22886327 22 Sep 13 14:58:51.320 ospf_send_dbd: to 6.6.6.6(10.2.0.6) intf Gi0/0/0/1 seq 0x22886327 23 Sep 13 14:58:51.327 ospf_rcv_dbd: from 6.6.6.6(10.2.0.6) intf Gi0/0/0/1 seq 0x22886328 24 Sep 13 14:58:51.327 ospf_output: LSA pkt, src 10.2.0.5 dest 10.2.0.6 area 0.0.0.2 intf Gi0/0/0/1 25 Sep 13 14:58:51.327 ospf_send_req: LS REQ pkt, nbr 6.6.6.6/10.2.0.6 cnt 12 unsent 0 26 Sep 13 14:58:51.327 ospf_send_dbd: to 6.6.6.6(10.2.0.6) intf Gi0/0/0/1 seq 0x22886328 27 Sep 13 14:58:51.333 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 4 paksize 526 28 Sep 13 14:58:51.333 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 3 paksize 246 29 Sep 13 14:58:51.333 ospf_output: LSA pkt, src 10.2.0.5 dest 10.2.0.6 area 0.0.0.2 intf Gi0/0/0/1 30 Sep 13 14:58:51.335 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 31 Sep 13 14:58:51.341 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 4 paksize 150 32 Sep 13 14:58:51.369 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 33 Sep 13 14:58:51.375 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 4 paksize 190 34 Sep 13 14:58:51.386 ospf_output: LSA pkt, src 10.2.0.5 dest 224.0.0.6 area 0.0.0.2 intf Gi0/0/0/1 35 Sep 13 14:58:51.402 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 36 Sep 13 14:58:53.334 ospf_output: LSA pkt, src 10.2.0.5 dest 224.0.0.6 area 0.0.0.2 intf Gi0/0/0/1 37 Sep 13 14:58:53.340 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 5 paksize 334 38 Sep 13 14:58:53.342 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 5 paksize 394 39 Sep 13 14:58:56.143 ospf_output: LSA pkt, src 10.2.35.5 dest 10.2.35.3 area 0.0.0.2 intf Gi0/0/0/0 40 Sep 13 14:58:56.349 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 4 paksize 174 41 Sep 13 14:58:56.352 ospf_output: LSA pkt, src 10.2.0.5 dest 224.0.0.6 area 0.0.0.2 intf Gi0/0/0/1 42 Sep 13 14:58:56.515 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 4 paksize 174 43 Sep 13 14:58:56.518 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 44 Sep 13 14:58:58.152 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 5 paksize 174 45 Sep 13 14:58:58.350 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 46 Sep 13 14:58:58.358 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 5 paksize 154 47 Sep 13 14:58:58.516 ospf_output: LSA pkt, src 10.2.0.5 dest 224.0.0.6 area 0.0.0.2 intf Gi0/0/0/1 48 Sep 13 15:31:15.868 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 4 paksize 222 49 Sep 13 15:31:15.871 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 50 Sep 13 15:31:15.967 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 4 paksize 154 51 Sep 13 15:31:15.969 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 52 Sep 13 15:31:17.663 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 4 paksize 166 53 Sep 13 15:31:17.666 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 54 Sep 13 15:31:17.812 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 4 paksize 314 55 Sep 13 15:31:17.815 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 56 Sep 13 15:31:17.869 ospf_output: LSA pkt, src 10.2.0.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/1 57 Sep 13 15:31:17.930 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 5 paksize 334 58 Sep 13 15:31:44.468 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 4 paksize 166 59 Sep 13 15:31:44.470 ospf_output: LSA pkt, src 10.2.0.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/1 60 Sep 13 15:31:44.602 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 4 paksize 314 61 Sep 13 15:31:44.604 ospf_output: LSA pkt, src 10.2.0.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/1 62 Sep 13 15:31:46.468 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 63 Sep 13 15:31:46.507 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 5 paksize 274 64 Sep 13 15:32:10.420 ospf_output: LSA pkt, src 10.2.35.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/0 65 Sep 13 15:32:10.420 ospf_output: LSA pkt, src 10.2.0.5 dest 224.0.0.5 area 0.0.0.2 intf Gi0/0/0/1 66 Sep 13 15:32:12.429 ospf_do_hello_packets: LSA pkt, src 10.2.0.6 intf Gi0/0/0/1 type 5 paksize 134 67 Sep 13 15:32:12.430 ospf_do_hello_packets: LSA pkt, src 10.2.35.3 intf Gi0/0/0/0 type 5 paksize 134 RP/0/RP0/CPU0:R5#show ospf trace events Sun Sep 13 15:49:53.332 UTC Traces for OSPF 1 (Sun Sep 13 15:49:53) Traces returned/requested/available: 110/4096/110 Trace buffer: events 1 Sep 13 14:57:48.689 ospf_nsr_event_manager_create: Created channel, ISSU-role 0, HA-role 0 2 Sep 13 14:57:49.891 ospf_if_issu_map_complete_cb: IM issu if mapping complete cb: mapping_complete 0x1 3 Sep 13 14:57:49.891 ospf_get_instance_by_name: : Name unknown in inst AVL name tree 4 Sep 13 14:57:49.891 ospf_create_instance: : instance alloc ptr 0x5c9f8bc39730 5 Sep 13 14:57:49.891 ospf_create_instance: : AVL instance insert ptr 0x5c9f8bc39730 6 Sep 13 14:57:49.892 ospf_create_instance: lock instance 0x0 ptr 0x5c9f8bc39730 refcount 1 7 Sep 13 14:57:50.174 ospf_ds_init: op_issu_id 0 8 Sep 13 14:57:51.393 ospf_create_area: : area 0.0.0.0 (0x5c9f8bcd3e20) dummy 1 9 Sep 13 14:57:51.575 ospf_nsr_fsm_dispatch_event: dispatching event 32, state 0 -> 0, rev 0 10 Sep 13 14:57:51.575 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 11 Sep 13 14:57:51.575 ospf_nsr_fsm_dispatch_event: dispatching event 32, state 0 -> 0, rev 0 12 Sep 13 14:57:51.575 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 13 Sep 13 14:57:51.575 ospf_nsr_fsm_dispatch_event: dispatching event 14, state 0 -> 0, rev 0 14 Sep 13 14:57:51.575 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 15 Sep 13 14:57:51.575 ospf_nsr_fsm_dispatch_event: dispatching event 14, state 0 -> 0, rev 0 16 Sep 13 14:57:51.575 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 17 Sep 13 14:57:51.575 ospf_nsr_fsm_dispatch_event: dispatching event 20, state 0 -> 1, rev 0 18 Sep 13 14:57:51.575 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 19 Sep 13 14:57:51.790 ospf_register_end_of_cfg: Attempt to register for end of config notification 20 Sep 13 14:57:51.877 ospf_cfgmgr_end_of_cfg_reg_cb: Received registration callback 21 Sep 13 14:57:51.877 ospf_cfgmgr_end_of_cfg_reg_cb: Registered for end of config 22 Sep 13 14:57:51.877 ospf_register_end_of_cfg: Registration accepted 23 Sep 13 14:57:51.981 ospf_nsr_fsm_dispatch_event: dispatching event 20, state 0 -> 1, rev 0 24 Sep 13 14:57:51.981 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 25 Sep 13 14:57:51.987 ospf_ds_connect_cb: DS Connected 26 Sep 13 14:57:51.987 ospf_publish_ncd_ds_endp: Proc role not yet known 27 Sep 13 14:57:51.987 ospf_register_ncd_ds_service: Proc role not yet known 28 Sep 13 14:57:51.991 ospf_publish_ncd_ds_endp: Proc role not yet known 29 Sep 13 14:57:51.991 ospf_register_ncd_ds_service: Proc role not yet known 30 Sep 13 14:57:51.995 ospf_update_sckt_vrf_opt: vrf opts update OK for vrfid 0x60000000 31 Sep 13 14:57:51.996 ospf_publish_ncd_ds_endp: DS NCD service successfully published, ISSU role 1, HA role 1, 32 Sep 13 14:57:51.996 ospf_register_ncd_ds_service: Successfully registered for NCD DS notification 33 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: dispatching event 31, state 1 -> 1, rev 0 34 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 35 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 0 36 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 37 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: dispatching event 31, state 1 -> 1, rev 0 38 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 39 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 0 40 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 41 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: dispatching event 15, state 1 -> 1, rev 0 42 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 43 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: dispatching event 15, state 1 -> 1, rev 0 44 Sep 13 14:57:51.996 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 45 Sep 13 14:57:51.997 ospf_nsr_msg_chan_update: Update channel, ISSU-role 1, HA-role 1 46 Sep 13 14:57:52.177 ospf_bfd_connect: Attempt to connect to BFD server 47 Sep 13 14:57:52.181 ospf_rtrid_set_configured: new_rtr_id 5.5.5.5 48 Sep 13 14:57:52.181 ospf_rtrid_set_configured: rtrid config by value, op_conf_rtr_id 5.5.5.5 vrfid 60000000 49 Sep 13 14:57:52.181 ospf_ds_publish_service_cb: DS NCD service publised succesfully 50 Sep 13 14:57:52.184 ospf_service_redist_delete: Deleting old redist routes, instance 0x60000000 51 Sep 13 14:57:52.184 ospf_ds_register_cb: DS NCD service registered succesfully, reg-handle 0x1 52 Sep 13 14:57:52.185 ospf_soft_reset: start 53 Sep 13 14:57:52.185 ospf_flush_type5: flush external links 54 Sep 13 14:57:52.185 ospf_flush_type11: flush opaque AS links 55 Sep 13 14:57:52.190 ospf_soft_reset_up: inst 0x5c9f8bc39730 vrfid 0x60000000 delete rid tuple, alloc new rid 56 Sep 13 14:57:52.190 ospf_rtrid_allocate: checking for old rtrid 0x60000000 57 Sep 13 14:57:52.190 ospf_rtrid_allocate: checking for rtrid config 58 Sep 13 14:57:52.190 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set by numeric config: 5.5.5.5 59 Sep 13 14:57:52.194 ospf_soft_reset_up: rtrid: new 5.5.5.5 old 0.0.0.0 60 Sep 13 14:57:52.194 ospf_soft_reset_up: rtrid changed, check vlink for reconfig 61 Sep 13 14:57:52.194 ospf_ds_notify_service_cb: Number of EPs 1 62 Sep 13 14:57:52.194 ospf_ds_notify_service_cb: Setting new DS endp rev 1, number of EPs - 1 63 Sep 13 14:57:52.194 ospf_ncd_ds_endp_update: DS NCD endp, HA-role 1, ISSU-role 1 op_ospf_role 6 64 Sep 13 14:57:52.194 ospf_ncd_ds_endp_update: op_issu_id 0 issu_id 0 65 Sep 13 14:57:52.194 ospf_ncd_ds_endp_update: Ignore DS endp on V1A, ha-role 1, issu-role 1 66 Sep 13 14:57:52.194 ospf_ncd_ds_endp_clean_stale: Cleaning stale NCD endpoints 67 Sep 13 14:57:52.194 ospf_ncd_ds_endp_clean_stale: peer handle not set yet 68 Sep 13 14:57:52.194 ospf_ncd_ds_endp_clean_stale: peer handle not set yet 69 Sep 13 14:57:52.274 ospf_apply_area: instance ptr 0x5c9f8bc39730 70 Sep 13 14:57:52.274 ospf_create_area: : area 0.0.0.2 (0x5c9f8bdb5840) dummy 0 71 Sep 13 14:57:52.279 ospf_bfd_bind_callback: Bind to BFD server - success 72 Sep 13 14:57:52.279 ospf_bfd_conn_callback: Connection to BFD established 73 Sep 13 14:57:52.279 ospf_bfd_send_create_batch: Batch size 0 74 Sep 13 14:57:52.279 ospf_bfd_end_of_replay: Sending EoR to BFD 75 Sep 13 14:57:52.386 ospf_activate_area_ptr: inactive area, area 0.0.0.2 (0x5c9f8bdb5840) op_area_count 1 76 Sep 13 14:57:52.386 ospf_activate_area: active area, area 0.0.0.2 77 Sep 13 14:57:52.387 ospf_activate_area: active area, area 0.0.0.2 78 Sep 13 14:57:52.387 ospf_service_area_nssa_default: nssa_default, ar_id 0.0.0.2 forw_addr 0.0.0.0 metric 16777215 79 Sep 13 14:57:53.185 ospf_service_redist_summary: Start redist-scanning 80 Sep 13 14:58:00.474 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.2 81 Sep 13 14:58:00.474 ospf_service_area_nssa_default: nssa_default, ar_id 0.0.0.2 forw_addr 0.0.0.0 metric 16777215 82 Sep 13 14:58:00.487 ospf_if_rtr_id_callback: Router-id sent by IPARM: 5.5.5.5 83 Sep 13 14:58:01.988 ospf_cfgmgr_end_of_cfg_cb: Received end of config callback 84 Sep 13 14:58:01.988 ospf_cfgmgr_end_of_cfg_cb: Success 85 Sep 13 14:58:01.988 ospf_nsr_fsm_dispatch_event: dispatching event 30, state 1 -> 1, rev 1 86 Sep 13 14:58:01.988 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 87 Sep 13 14:58:01.988 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 1 88 Sep 13 14:58:01.988 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 89 Sep 13 14:58:01.988 ospf_nsr_fsm_dispatch_event: dispatching event 30, state 1 -> 1, rev 1 90 Sep 13 14:58:01.988 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 91 Sep 13 14:58:01.988 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 1 92 Sep 13 14:58:01.988 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 93 Sep 13 14:58:03.185 ospf_service_redist_summary: Start redist-scanning 94 Sep 13 14:58:05.879 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.2 95 Sep 13 14:58:05.976 ospf_service_area_nssa_default: nssa_default, ar_id 0.0.0.2 forw_addr 0.0.0.0 metric 16777215 96 Sep 13 14:58:11.302 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.2 97 Sep 13 14:58:13.185 ospf_service_redist_summary: Start redist-scanning 98 Sep 13 14:58:45.975 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.2 99 Sep 13 14:58:51.332 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.2 100 Sep 13 14:58:57.349 ospf_service_maxage: delete MAXAGE lsa, queue size 4 101 Sep 13 14:58:57.349 ospf_service_maxage: processed 4 LSAs from MAXAGE queue, requeued 4, current size 4 102 Sep 13 14:58:58.349 ospf_service_maxage: delete MAXAGE lsa, queue size 4 103 Sep 13 14:58:58.349 ospf_service_maxage: processed 4 LSAs from MAXAGE queue, requeued 4, current size 4 104 Sep 13 14:58:59.350 ospf_service_maxage: delete MAXAGE lsa, queue size 2 105 Sep 13 14:58:59.350 ospf_service_maxage: processed 2 LSAs from MAXAGE queue, requeued 0, current size 0 106 Sep 13 14:58:59.454 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.2 107 Sep 13 14:59:51.997 ospf_nsr_fsm_dispatch_event: dispatching event 22, state 1 -> 1, rev 1 108 Sep 13 14:59:51.997 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 109 Sep 13 14:59:51.997 ospf_nsr_fsm_dispatch_event: dispatching event 22, state 1 -> 1, rev 1 110 Sep 13 14:59:51.997 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 RP/0/RP0/CPU0:R5#show ospf trace adj Sun Sep 13 15:49:54.048 UTC Traces for OSPF 1 (Sun Sep 13 15:49:54) Traces returned/requested/available: 72/8192/72 Trace buffer: adj 1 Sep 13 14:57:49.891 ospf_create_instance: Instance 0x0 quiesce_time INIT 2 Sep 13 14:57:51.393 ospf_create_area: Area 0.0.0.0 quiesce_time INIT 3 Sep 13 14:57:52.184 ospf_build_ex_lsa: no rtrid to build external LSA 4 Sep 13 14:57:52.274 ospf_create_area: Area 0.0.0.2 quiesce_time INIT 5 Sep 13 14:57:53.185 ospf_service_redist_summary: end scanning, elapsed time 000000000.000029703 6 Sep 13 14:58:00.476 ospf_build_rtr_lsa: area 0.0.0.2 rtrid 5.5.5.5 seq 0x80000001 vrfid 0x60000000 7 Sep 13 14:58:03.185 ospf_service_redist_summary: scan for redist 8 Sep 13 14:58:03.185 ospf_service_redist_summary: end scanning, elapsed time 000000000.000034613 9 Sep 13 14:58:06.026 ospf_build_rtr_lsa: area 0.0.0.2 rtrid 5.5.5.5 seq 0x80000002 vrfid 0x60000000 10 Sep 13 14:58:11.275 ospf_if_inc_nbr_cnt: Gi0/0/0/0 - count in inst 1 (ex_load 0), on idb 1 11 Sep 13 14:58:11.275 ospf_inc_nbr_form_cnt: Instance 0x60000000 quiesce_time INIT 12 Sep 13 14:58:11.275 ospf_inc_nbr_form_cnt: Area 0.0.0.2 quiesce_time INIT 13 Sep 13 14:58:11.275 ospf_inc_nbr_form_cnt: nbr 3.3.3.3 forming Gi0/0/0/0, area 0.0.0.2 14 Sep 13 14:58:11.275 ospf_inc_nbr_form_cnt: #Nbrs: (ar: 1, inst: 1) forming, 0 full, area 0.0.0.2 15 Sep 13 14:58:11.275 ospf_log_adj_chg: nbr 3.3.3.3/10.2.35.3 changes from 0 to 2 16 Sep 13 14:58:11.275 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 0 pkts 0 LSAs, LSA-Upd Rcv: 0 pkts 0 LSAs 17 Sep 13 14:58:11.275 ospf_log_adj_chg: DBD Rcv: 0 pkts 0 LSAs, area 0.0.0.2, vrfid 0x60000000 18 Sep 13 14:58:11.275 ospf_log_adj_chg: nbr 3.3.3.3/10.2.35.3 changes from 2 to 3 19 Sep 13 14:58:11.275 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 0 pkts 0 LSAs, LSA-Upd Rcv: 0 pkts 0 LSAs 20 Sep 13 14:58:11.275 ospf_log_adj_chg: DBD Rcv: 0 pkts 0 LSAs, area 0.0.0.2, vrfid 0x60000000 21 Sep 13 14:58:11.275 ospf_log_adj_chg: nbr 3.3.3.3/10.2.35.3 changes from 3 to 4 22 Sep 13 14:58:11.275 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 0 pkts 0 LSAs, LSA-Upd Rcv: 0 pkts 0 LSAs 23 Sep 13 14:58:11.275 ospf_log_adj_chg: DBD Rcv: 0 pkts 0 LSAs, area 0.0.0.2, vrfid 0x60000000 24 Sep 13 14:58:11.287 ospf_rcv_dbd: first DBD and we are not SLAVE 25 Sep 13 14:58:11.287 ospf_log_adj_chg: nbr 3.3.3.3/10.2.35.3 changes from 4 to 5 26 Sep 13 14:58:11.287 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 0 pkts 0 LSAs, LSA-Upd Rcv: 0 pkts 0 LSAs 27 Sep 13 14:58:11.287 ospf_log_adj_chg: DBD Rcv: 0 pkts 0 LSAs, area 0.0.0.2, vrfid 0x60000000 28 Sep 13 14:58:11.287 ospf_nbr_inc_ex_load_count: Inst: 1 neighbors in ex_load Gi0/0/0/0, area 0.0.0.2, instance quiesce_time updated 29 Sep 13 14:58:11.287 ospf_nbr_inc_ex_load_count: Area: 1 neighbors in ex_load Gi0/0/0/0, area 0.0.0.2, area quiesce_time updated 30 Sep 13 14:58:11.287 build_dbd_sum: took 0 ms for nbr 3.3.3.3 count 1 vrf 0x60000000 31 Sep 13 14:58:11.294 ospf_log_adj_chg: nbr 3.3.3.3/10.2.35.3 changes from 5 to 6 32 Sep 13 14:58:11.294 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 0 pkts 0 LSAs, LSA-Upd Rcv: 0 pkts 0 LSAs 33 Sep 13 14:58:11.294 ospf_log_adj_chg: DBD Rcv: 2 pkts 5 LSAs, area 0.0.0.2, vrfid 0x60000000 34 Sep 13 14:58:11.301 ospf_log_adj_chg: nbr 3.3.3.3/10.2.35.3 changes from 6 to 7 35 Sep 13 14:58:11.301 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 1 pkts 5 LSAs, LSA-Upd Rcv: 1 pkts 5 LSAs 36 Sep 13 14:58:11.301 ospf_log_adj_chg: DBD Rcv: 2 pkts 5 LSAs, area 0.0.0.2, vrfid 0x60000000 37 Sep 13 14:58:11.302 ospf_if_inc_nbr_full_cnt: Gi0/0/0/0 - full count in inst 1, on idb 1, area fullcnt 1 38 Sep 13 14:58:11.302 ospf_dec_nbr_form_cnt: nbr 3.3.3.3 forming Gi0/0/0/0, area 0.0.0.2 39 Sep 13 14:58:11.302 ospf_dec_nbr_form_cnt: #Nbrs: (ar: 0, inst: 0) forming, 1 full, area 0.0.0.2 40 Sep 13 14:58:11.314 ospf_if_inc_nbr_cnt: Gi0/0/0/1 - count in inst 2 (ex_load 0), on idb 1 41 Sep 13 14:58:11.314 ospf_inc_nbr_form_cnt: nbr 6.6.6.6 forming Gi0/0/0/1, area 0.0.0.2 42 Sep 13 14:58:11.314 ospf_inc_nbr_form_cnt: #Nbrs: (ar: 1, inst: 1) forming, 1 full, area 0.0.0.2 43 Sep 13 14:58:11.314 ospf_log_adj_chg: nbr 6.6.6.6/10.2.0.6 changes from 0 to 2 44 Sep 13 14:58:11.314 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 0 pkts 0 LSAs, LSA-Upd Rcv: 0 pkts 0 LSAs 45 Sep 13 14:58:11.314 ospf_log_adj_chg: DBD Rcv: 0 pkts 0 LSAs, area 0.0.0.2, vrfid 0x60000000 46 Sep 13 14:58:11.373 ospf_build_rtr_lsa: area 0.0.0.2 rtrid 5.5.5.5 seq 0x80000003 vrfid 0x60000000 47 Sep 13 14:58:13.185 ospf_service_redist_summary: scan for redist 48 Sep 13 14:58:13.185 ospf_service_redist_summary: end scanning, elapsed time 000000000.000015389 49 Sep 13 14:58:21.216 ospf_log_adj_chg: nbr 6.6.6.6/10.2.0.6 changes from 2 to 3 50 Sep 13 14:58:21.216 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 0 pkts 0 LSAs, LSA-Upd Rcv: 0 pkts 0 LSAs 51 Sep 13 14:58:21.216 ospf_log_adj_chg: DBD Rcv: 0 pkts 0 LSAs, area 0.0.0.2, vrfid 0x60000000 52 Sep 13 14:58:45.975 ospf_log_adj_chg: nbr 6.6.6.6/10.2.0.6 changes from 3 to 4 53 Sep 13 14:58:45.975 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 0 pkts 0 LSAs, LSA-Upd Rcv: 0 pkts 0 LSAs 54 Sep 13 14:58:45.975 ospf_log_adj_chg: DBD Rcv: 0 pkts 0 LSAs, area 0.0.0.2, vrfid 0x60000000 55 Sep 13 14:58:51.319 ospf_log_adj_chg: nbr 6.6.6.6/10.2.0.6 changes from 4 to 5 56 Sep 13 14:58:51.319 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 0 pkts 0 LSAs, LSA-Upd Rcv: 0 pkts 0 LSAs 57 Sep 13 14:58:51.319 ospf_log_adj_chg: DBD Rcv: 0 pkts 0 LSAs, area 0.0.0.2, vrfid 0x60000000 58 Sep 13 14:58:51.319 ospf_nbr_inc_ex_load_count: Inst: 1 neighbors in ex_load Gi0/0/0/1, area 0.0.0.2, instance quiesce_time updated 59 Sep 13 14:58:51.319 ospf_nbr_inc_ex_load_count: Area: 1 neighbors in ex_load Gi0/0/0/1, area 0.0.0.2, area quiesce_time updated 60 Sep 13 14:58:51.319 build_dbd_sum: took 0 ms for nbr 6.6.6.6 count 11 vrf 0x60000000 61 Sep 13 14:58:51.326 ospf_log_adj_chg: nbr 6.6.6.6/10.2.0.6 changes from 5 to 6 62 Sep 13 14:58:51.326 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 0 pkts 0 LSAs, LSA-Upd Rcv: 0 pkts 0 LSAs 63 Sep 13 14:58:51.326 ospf_log_adj_chg: DBD Rcv: 2 pkts 12 LSAs, area 0.0.0.2, vrfid 0x60000000 64 Sep 13 14:58:51.332 ospf_log_adj_chg: nbr 6.6.6.6/10.2.0.6 changes from 6 to 7 65 Sep 13 14:58:51.332 ospf_log_adj_chg: Flooding stats: LSA-Req sent: 1 pkts 12 LSAs, LSA-Upd Rcv: 1 pkts 12 LSAs 66 Sep 13 14:58:51.332 ospf_log_adj_chg: DBD Rcv: 2 pkts 12 LSAs, area 0.0.0.2, vrfid 0x60000000 67 Sep 13 14:58:51.332 ospf_if_inc_nbr_full_cnt: Gi0/0/0/1 - full count in inst 2, on idb 1, area fullcnt 2 68 Sep 13 14:58:51.332 ospf_dec_nbr_form_cnt: nbr 6.6.6.6 forming Gi0/0/0/1, area 0.0.0.2 69 Sep 13 14:58:51.332 ospf_dec_nbr_form_cnt: #Nbrs: (ar: 0, inst: 0) forming, 2 full, area 0.0.0.2 70 Sep 13 14:58:51.385 ospf_build_rtr_lsa: area 0.0.0.2 rtrid 5.5.5.5 seq 0x80000004 vrfid 0x60000000 71 Sep 13 14:59:31.326 ospf_nbr_hold_dbd: Timer expired (nbr_hold_dbd): nbr_id 6.6.6.6 72 Sep 13 15:32:10.419 ospf_build_rtr_lsa: area 0.0.0.2 rtrid 5.5.5.5 seq 0x80000005 vrfid 0x60000000