RP/0/RP0/CPU0:R4#show ospf trace events | include rtrid Wed Sep 23 08:23:36.772 UTC 76 Sep 23 07:33:00.606 ospf_rtrid_allocate: checking for old rtrid 0x60000000 77 Sep 23 07:33:00.606 ospf_rtrid_allocate: checking for rtrid config 78 Sep 23 07:33:00.797 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set using our intf: 10.0.14.4 149 Sep 23 08:10:08.414 ospf_rtrid_allocate: checking for old rtrid 0x60000000 150 Sep 23 08:10:08.414 ospf_rtrid_allocate: checking for rtrid config 151 Sep 23 08:10:08.509 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set using our intf: 10.0.14.4 152 Sep 23 08:10:08.509 ospf_soft_reset_up: rtrid: new 10.0.14.4 old 10.0.14.4 RP/0/RP0/CPU0:R4#show ospf trace events Wed Sep 23 08:23:37.654 UTC Traces for OSPF 1 (Wed Sep 23 08:23:38) Traces returned/requested/available: 174/4096/174 Trace buffer: events 1 Sep 23 07:32:43.799 ospf_nsr_event_manager_create: Created channel, ISSU-role 0, HA-role 0 2 Sep 23 07:32:44.906 ospf_if_issu_map_complete_cb: IM issu if mapping complete cb: mapping_complete 0x1 3 Sep 23 07:32:44.906 ospf_get_instance_by_name: : Name unknown in inst AVL name tree 4 Sep 23 07:32:44.906 ospf_create_instance: : instance alloc ptr 0x6016f3733710 5 Sep 23 07:32:44.906 ospf_create_instance: : AVL instance insert ptr 0x6016f3733710 6 Sep 23 07:32:44.906 ospf_create_instance: lock instance 0x0 ptr 0x6016f3733710 refcount 1 7 Sep 23 07:32:45.194 ospf_ds_init: op_issu_id 0 8 Sep 23 07:32:46.499 ospf_create_area: : area 0.0.0.0 (0x6016f37cde00) dummy 1 9 Sep 23 07:32:46.702 ospf_nsr_fsm_dispatch_event: dispatching event 32, state 0 -> 0, rev 0 10 Sep 23 07:32:46.702 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 11 Sep 23 07:32:46.702 ospf_nsr_fsm_dispatch_event: dispatching event 32, state 0 -> 0, rev 0 12 Sep 23 07:32:46.702 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 13 Sep 23 07:32:46.702 ospf_nsr_fsm_dispatch_event: dispatching event 14, state 0 -> 0, rev 0 14 Sep 23 07:32:46.702 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 15 Sep 23 07:32:46.702 ospf_nsr_fsm_dispatch_event: dispatching event 14, state 0 -> 0, rev 0 16 Sep 23 07:32:46.702 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 17 Sep 23 07:32:46.702 ospf_nsr_fsm_dispatch_event: dispatching event 20, state 0 -> 1, rev 0 18 Sep 23 07:32:46.702 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 19 Sep 23 07:32:47.003 ospf_register_end_of_cfg: Attempt to register for end of config notification 20 Sep 23 07:32:47.013 ospf_cfgmgr_end_of_cfg_reg_cb: Received registration callback 21 Sep 23 07:32:47.013 ospf_cfgmgr_end_of_cfg_reg_cb: Registered for end of config 22 Sep 23 07:32:47.014 ospf_register_end_of_cfg: Registration accepted 23 Sep 23 07:32:47.111 ospf_nsr_fsm_dispatch_event: dispatching event 20, state 0 -> 1, rev 0 24 Sep 23 07:32:47.111 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 25 Sep 23 07:32:47.196 ospf_ds_connect_cb: DS Connected 26 Sep 23 07:32:47.196 ospf_publish_ncd_ds_endp: Proc role not yet known 27 Sep 23 07:32:47.196 ospf_register_ncd_ds_service: Proc role not yet known 28 Sep 23 07:32:47.199 ospf_publish_ncd_ds_endp: Proc role not yet known 29 Sep 23 07:32:47.199 ospf_register_ncd_ds_service: Proc role not yet known 30 Sep 23 07:32:47.204 ospf_update_sckt_vrf_opt: vrf opts update OK for vrfid 0x60000000 31 Sep 23 07:32:47.204 ospf_publish_ncd_ds_endp: DS NCD service successfully published, ISSU role 1, HA role 1, 32 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: dispatching event 31, state 1 -> 1, rev 0 33 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 34 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 0 35 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 36 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: dispatching event 31, state 1 -> 1, rev 0 37 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 38 Sep 23 07:32:47.204 ospf_register_ncd_ds_service: Successfully registered for NCD DS notification 39 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 0 40 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 41 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: dispatching event 15, state 1 -> 1, rev 0 42 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 43 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: dispatching event 15, state 1 -> 1, rev 0 44 Sep 23 07:32:47.204 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 45 Sep 23 07:32:47.205 ospf_nsr_msg_chan_update: Update channel, ISSU-role 1, HA-role 1 46 Sep 23 07:32:47.307 ospf_bfd_connect: Attempt to connect to BFD server 47 Sep 23 07:32:47.310 ospf_apply_area: instance ptr 0x6016f3733710 48 Sep 23 07:32:47.310 ospf_create_area: : area 0.0.0.0 (0x6016f387ea30) dummy 0 49 Sep 23 07:32:47.311 ospf_ds_publish_service_cb: DS NCD service publised succesfully 50 Sep 23 07:32:47.312 ospf_ds_register_cb: DS NCD service registered succesfully, reg-handle 0x1 51 Sep 23 07:32:47.314 ospf_service_redist_delete: Deleting old redist routes, instance 0x60000000 52 Sep 23 07:32:47.315 ospf_ds_notify_service_cb: Number of EPs 1 53 Sep 23 07:32:47.315 ospf_ds_notify_service_cb: Setting new DS endp rev 1, number of EPs - 1 54 Sep 23 07:32:47.315 ospf_ncd_ds_endp_update: DS NCD endp, HA-role 1, ISSU-role 1 op_ospf_role 6 55 Sep 23 07:32:47.315 ospf_ncd_ds_endp_update: op_issu_id 0 issu_id 0 56 Sep 23 07:32:47.315 ospf_ncd_ds_endp_update: Ignore DS endp on V1A, ha-role 1, issu-role 1 57 Sep 23 07:32:47.315 ospf_ncd_ds_endp_clean_stale: Cleaning stale NCD endpoints 58 Sep 23 07:32:47.315 ospf_ncd_ds_endp_clean_stale: peer handle not set yet 59 Sep 23 07:32:47.315 ospf_ncd_ds_endp_clean_stale: peer handle not set yet 60 Sep 23 07:32:47.397 ospf_activate_area_ptr: inactive area, area 0.0.0.0 (0x6016f387ea30) op_area_count 1 61 Sep 23 07:32:47.409 ospf_bfd_bind_callback: Bind to BFD server - success 62 Sep 23 07:32:47.409 ospf_bfd_conn_callback: Connection to BFD established 63 Sep 23 07:32:47.409 ospf_bfd_send_create_batch: Batch size 0 64 Sep 23 07:32:47.409 ospf_bfd_end_of_replay: Sending EoR to BFD 65 Sep 23 07:32:48.315 ospf_service_redist_summary: Start redist-scanning 66 Sep 23 07:32:57.196 ospf_cfgmgr_end_of_cfg_cb: Received end of config callback 67 Sep 23 07:32:57.196 ospf_cfgmgr_end_of_cfg_cb: Success 68 Sep 23 07:32:57.196 ospf_nsr_fsm_dispatch_event: dispatching event 30, state 1 -> 1, rev 1 69 Sep 23 07:32:57.196 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 70 Sep 23 07:32:57.196 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 1 71 Sep 23 07:32:57.196 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 72 Sep 23 07:32:57.196 ospf_nsr_fsm_dispatch_event: dispatching event 30, state 1 -> 1, rev 1 73 Sep 23 07:32:57.196 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 74 Sep 23 07:32:57.196 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 1 75 Sep 23 07:32:57.196 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 76 Sep 23 07:33:00.606 ospf_rtrid_allocate: checking for old rtrid 0x60000000 77 Sep 23 07:33:00.606 ospf_rtrid_allocate: checking for rtrid config 78 Sep 23 07:33:00.797 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set using our intf: 10.0.14.4 79 Sep 23 07:33:00.797 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 80 Sep 23 07:33:01.798 ospf_service_redist_summary: Start redist-scanning 81 Sep 23 07:33:40.798 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 82 Sep 23 07:33:40.828 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 83 Sep 23 07:33:57.076 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 84 Sep 23 07:34:47.204 ospf_nsr_fsm_dispatch_event: dispatching event 22, state 1 -> 1, rev 1 85 Sep 23 07:34:47.204 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 86 Sep 23 07:34:47.204 ospf_nsr_fsm_dispatch_event: dispatching event 22, state 1 -> 1, rev 1 87 Sep 23 07:34:47.204 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 88 Sep 23 07:40:55.097 ospf_clear_stats_instance: 89 Sep 23 07:40:55.097 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 1.1.1.1/10.0.14.1 90 Sep 23 07:40:55.097 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 91 Sep 23 07:45:01.522 ospf_clear_stats_instance: 92 Sep 23 07:45:01.522 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 1.1.1.1/10.0.14.1 93 Sep 23 07:45:01.522 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 94 Sep 23 07:48:11.600 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 95 Sep 23 07:48:11.600 ospf_bfd_delete_one_session: nbr 10.0.14.1 intf Gi0/0/0/0 (area 0.0.0.0), delete BFD session con state 0 96 Sep 23 07:48:12.654 ospf_service_maxage: delete MAXAGE lsa, queue size 1 97 Sep 23 07:48:12.654 ospf_service_maxage: processed 1 LSAs from MAXAGE queue, requeued 0, current size 0 98 Sep 23 07:50:29.087 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 99 Sep 23 07:50:29.107 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 100 Sep 23 07:50:30.615 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 101 Sep 23 07:52:51.137 ospf_clear_stats_instance: 102 Sep 23 07:52:51.137 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 1.1.1.1/10.0.14.1 103 Sep 23 07:52:51.137 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 104 Sep 23 07:55:33.418 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 105 Sep 23 07:55:33.418 ospf_bfd_delete_one_session: nbr 10.0.14.1 intf Gi0/0/0/0 (area 0.0.0.0), delete BFD session con state 0 106 Sep 23 07:55:34.332 ospf_service_maxage: delete MAXAGE lsa, queue size 2 107 Sep 23 07:55:34.332 ospf_service_maxage: processed 2 LSAs from MAXAGE queue, requeued 1, current size 1 108 Sep 23 07:55:35.332 ospf_service_maxage: delete MAXAGE lsa, queue size 1 109 Sep 23 07:55:35.332 ospf_service_maxage: processed 1 LSAs from MAXAGE queue, requeued 0, current size 0 110 Sep 23 07:55:35.542 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 111 Sep 23 07:55:35.562 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 112 Sep 23 07:55:42.482 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 113 Sep 23 07:57:29.598 ospf_clear_stats_instance: 114 Sep 23 07:57:29.598 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 1.1.1.1/10.0.14.1 115 Sep 23 07:57:29.598 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 116 Sep 23 08:00:14.119 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 117 Sep 23 08:00:14.119 ospf_bfd_delete_one_session: nbr 10.0.14.1 intf Gi0/0/0/0 (area 0.0.0.0), delete BFD session con state 0 118 Sep 23 08:00:15.095 ospf_service_maxage: delete MAXAGE lsa, queue size 2 119 Sep 23 08:00:15.095 ospf_service_maxage: processed 2 LSAs from MAXAGE queue, requeued 1, current size 1 120 Sep 23 08:00:16.095 ospf_service_maxage: delete MAXAGE lsa, queue size 1 121 Sep 23 08:00:16.095 ospf_service_maxage: processed 1 LSAs from MAXAGE queue, requeued 0, current size 0 122 Sep 23 08:00:23.567 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 123 Sep 23 08:00:54.134 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 124 Sep 23 08:01:01.608 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 125 Sep 23 08:02:11.814 ospf_clear_stats_instance: 126 Sep 23 08:02:11.814 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 11.11.11.11/10.0.14.1 127 Sep 23 08:02:11.814 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 128 Sep 23 08:05:38.720 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 129 Sep 23 08:05:38.720 ospf_bfd_delete_one_session: nbr 10.0.14.1 intf Gi0/0/0/0 (area 0.0.0.0), delete BFD session con state 0 130 Sep 23 08:05:39.639 ospf_service_maxage: delete MAXAGE lsa, queue size 2 131 Sep 23 08:05:39.639 ospf_service_maxage: processed 2 LSAs from MAXAGE queue, requeued 1, current size 1 132 Sep 23 08:05:40.639 ospf_service_maxage: delete MAXAGE lsa, queue size 1 133 Sep 23 08:05:40.640 ospf_service_maxage: processed 1 LSAs from MAXAGE queue, requeued 0, current size 0 134 Sep 23 08:05:45.562 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 135 Sep 23 08:05:45.575 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 136 Sep 23 08:05:48.480 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 137 Sep 23 08:07:36.528 ospf_clear_stats_instance: 138 Sep 23 08:07:36.528 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 11.11.11.11/10.0.14.1 139 Sep 23 08:07:36.528 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 140 Sep 23 08:10:08.400 ospf_clear_process_fn: clearing process 141 Sep 23 08:10:08.400 ospf_soft_reset: start 142 Sep 23 08:10:08.401 ospf_flush_type5: flush external links 143 Sep 23 08:10:08.401 ospf_flush_type11: flush opaque AS links 144 Sep 23 08:10:08.401 ospf_flush_area: flush link states in area 0.0.0.0 145 Sep 23 08:10:08.401 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 146 Sep 23 08:10:08.401 ospf_bfd_delete_one_session: nbr 10.0.14.1 intf Gi0/0/0/0 (area 0.0.0.0), delete BFD session con state 0 147 Sep 23 08:10:08.405 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 148 Sep 23 08:10:08.414 ospf_soft_reset_up: inst 0x6016f3733710 vrfid 0x60000000 delete rid tuple, alloc new rid 149 Sep 23 08:10:08.414 ospf_rtrid_allocate: checking for old rtrid 0x60000000 150 Sep 23 08:10:08.414 ospf_rtrid_allocate: checking for rtrid config 151 Sep 23 08:10:08.509 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set using our intf: 10.0.14.4 152 Sep 23 08:10:08.509 ospf_soft_reset_up: rtrid: new 10.0.14.4 old 10.0.14.4 153 Sep 23 08:10:09.223 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 154 Sep 23 08:10:09.244 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 155 Sep 23 08:10:09.403 ospf_service_redist_summary: Start redist-scanning 156 Sep 23 08:10:18.772 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 157 Sep 23 08:12:03.908 ospf_clear_stats_instance: 158 Sep 23 08:12:03.908 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 11.11.11.11/10.0.14.1 159 Sep 23 08:12:03.908 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 160 Sep 23 08:14:39.940 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 161 Sep 23 08:14:39.940 ospf_bfd_delete_one_session: nbr 10.0.14.1 intf Gi0/0/0/0 (area 0.0.0.0), delete BFD session con state 0 162 Sep 23 08:14:40.930 ospf_service_maxage: delete MAXAGE lsa, queue size 2 163 Sep 23 08:14:40.930 ospf_service_maxage: processed 2 LSAs from MAXAGE queue, requeued 2, current size 2 164 Sep 23 08:14:41.930 ospf_service_maxage: delete MAXAGE lsa, queue size 2 165 Sep 23 08:14:41.930 ospf_service_maxage: processed 2 LSAs from MAXAGE queue, requeued 0, current size 0 166 Sep 23 08:14:49.787 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 167 Sep 23 08:15:23.121 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 168 Sep 23 08:15:28.162 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 169 Sep 23 08:16:39.635 ospf_clear_stats_instance: 170 Sep 23 08:16:39.635 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 1.1.1.1/10.0.14.1 171 Sep 23 08:16:39.635 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 172 Sep 23 08:21:13.897 ospf_clear_stats_instance: 173 Sep 23 08:21:13.897 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 1.1.1.1/10.0.14.1 174 Sep 23 08:21:13.897 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 RP/0/RP0/CPU0:R4#show ospf trace errors Wed Sep 23 08:23:38.613 UTC Traces for OSPF 1 (Wed Sep 23 08:23:39) Traces returned/requested/available: 6/8192/6 Trace buffer: errors 1 Sep 23 07:32:47.199 ospf_nsr_ev_free: Cannot free event 0xc8daafc0: flags 0x0,work 0x6016f3841450, handler 0x6016cd24802e 2 Sep 23 07:32:47.199 ospf_nsr_ev_free: inst 0x0, nsr_info 0x0, next 0x0 3 Sep 23 07:32:47.212 ospf_is_config_startup_done: Reading config startup done tuple failed [errno 0x40818600: 'sysdb' detected the 'warning' condition 'A SysDB client tried to access a nonexistent item or list an empty directory'] 4 Sep 23 07:33:31.080 ospf_rcv_dbd: WARN nbr state is 2WAY, nbr_state 3 5 Sep 23 07:33:36.037 ospf_rcv_dbd: WARN nbr state is 2WAY, nbr_state 3 6 Sep 23 07:33:40.713 ospf_rcv_dbd: WARN nbr state is 2WAY, nbr_state 3