RP/0/RP0/CPU0:R3#show ospf trace events Sun Oct 4 08:37:07.621 UTC Traces for OSPF 1 (Sun Oct 4 08:37:08) Traces returned/requested/available: 179/4096/179 Trace buffer: events 1 Oct 4 07:26:48.279 ospf_nsr_event_manager_create: Created channel, ISSU-role 0, HA-role 0 2 Oct 4 07:26:49.487 ospf_if_issu_map_complete_cb: IM issu if mapping complete cb: mapping_complete 0x1 3 Oct 4 07:26:49.487 ospf_get_instance_by_name: : Name unknown in inst AVL name tree 4 Oct 4 07:26:49.487 ospf_create_instance: : instance alloc ptr 0x5efa7aaf4720 5 Oct 4 07:26:49.487 ospf_create_instance: : AVL instance insert ptr 0x5efa7aaf4720 6 Oct 4 07:26:49.487 ospf_create_instance: lock instance 0x0 ptr 0x5efa7aaf4720 refcount 1 7 Oct 4 07:26:49.683 ospf_ds_init: op_issu_id 0 8 Oct 4 07:26:50.991 ospf_create_area: : area 0.0.0.0 (0x5efa7ab8ee10) dummy 1 9 Oct 4 07:26:51.175 ospf_nsr_fsm_dispatch_event: dispatching event 32, state 0 -> 0, rev 0 10 Oct 4 07:26:51.175 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 11 Oct 4 07:26:51.175 ospf_nsr_fsm_dispatch_event: dispatching event 32, state 0 -> 0, rev 0 12 Oct 4 07:26:51.175 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 13 Oct 4 07:26:51.175 ospf_nsr_fsm_dispatch_event: dispatching event 14, state 0 -> 0, rev 0 14 Oct 4 07:26:51.175 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 15 Oct 4 07:26:51.175 ospf_nsr_fsm_dispatch_event: dispatching event 14, state 0 -> 0, rev 0 16 Oct 4 07:26:51.175 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 17 Oct 4 07:26:51.175 ospf_nsr_fsm_dispatch_event: dispatching event 20, state 0 -> 1, rev 0 18 Oct 4 07:26:51.175 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 19 Oct 4 07:26:51.489 ospf_register_end_of_cfg: Attempt to register for end of config notification 20 Oct 4 07:26:51.583 ospf_cfgmgr_end_of_cfg_reg_cb: Received registration callback 21 Oct 4 07:26:51.583 ospf_cfgmgr_end_of_cfg_reg_cb: Registered for end of config 22 Oct 4 07:26:51.583 ospf_register_end_of_cfg: Registration accepted 23 Oct 4 07:26:51.689 ospf_nsr_fsm_dispatch_event: dispatching event 20, state 0 -> 1, rev 0 24 Oct 4 07:26:51.689 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 25 Oct 4 07:26:51.776 ospf_ds_connect_cb: DS Connected 26 Oct 4 07:26:51.776 ospf_publish_ncd_ds_endp: Proc role not yet known 27 Oct 4 07:26:51.776 ospf_register_ncd_ds_service: Proc role not yet known 28 Oct 4 07:26:51.779 ospf_publish_ncd_ds_endp: Proc role not yet known 29 Oct 4 07:26:51.779 ospf_register_ncd_ds_service: Proc role not yet known 30 Oct 4 07:26:51.783 ospf_update_sckt_vrf_opt: vrf opts update OK for vrfid 0x60000000 31 Oct 4 07:26:51.784 ospf_rtrid_set_configured: new_rtr_id 3.3.3.3 32 Oct 4 07:26:51.784 ospf_rtrid_set_configured: rtrid config by value, op_conf_rtr_id 3.3.3.3 vrfid 60000000 33 Oct 4 07:26:51.785 ospf_publish_ncd_ds_endp: DS NCD service successfully published, ISSU role 1, HA role 1, 34 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: dispatching event 31, state 1 -> 1, rev 0 35 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 36 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 0 37 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 38 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: dispatching event 31, state 1 -> 1, rev 0 39 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 40 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 0 41 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 42 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: dispatching event 15, state 1 -> 1, rev 0 43 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 44 Oct 4 07:26:51.785 ospf_register_ncd_ds_service: Successfully registered for NCD DS notification 45 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: dispatching event 15, state 1 -> 1, rev 0 46 Oct 4 07:26:51.785 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 47 Oct 4 07:26:51.786 ospf_nsr_msg_chan_update: Update channel, ISSU-role 1, HA-role 1 48 Oct 4 07:26:51.880 ospf_rtrid_allocate: checking for old rtrid 0x60000000 49 Oct 4 07:26:51.880 ospf_rtrid_allocate: checking for rtrid config 50 Oct 4 07:26:51.880 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set by numeric config: 3.3.3.3 51 Oct 4 07:26:51.975 ospf_bfd_connect: Attempt to connect to BFD server 52 Oct 4 07:26:51.978 ospf_ds_publish_service_cb: DS NCD service publised succesfully 53 Oct 4 07:26:51.980 ospf_ds_register_cb: DS NCD service registered succesfully, reg-handle 0x1 54 Oct 4 07:26:51.982 ospf_service_redist_delete: Deleting old redist routes, instance 0x60000000 55 Oct 4 07:26:51.982 ospf_ds_notify_service_cb: Number of EPs 1 56 Oct 4 07:26:51.982 ospf_ds_notify_service_cb: Setting new DS endp rev 1, number of EPs - 1 57 Oct 4 07:26:51.982 ospf_ncd_ds_endp_update: DS NCD endp, HA-role 1, ISSU-role 1 op_ospf_role 6 58 Oct 4 07:26:51.982 ospf_ncd_ds_endp_update: op_issu_id 0 issu_id 0 59 Oct 4 07:26:51.982 ospf_ncd_ds_endp_update: Ignore DS endp on V1A, ha-role 1, issu-role 1 60 Oct 4 07:26:51.982 ospf_ncd_ds_endp_clean_stale: Cleaning stale NCD endpoints 61 Oct 4 07:26:51.982 ospf_ncd_ds_endp_clean_stale: peer handle not set yet 62 Oct 4 07:26:51.982 ospf_ncd_ds_endp_clean_stale: peer handle not set yet 63 Oct 4 07:26:51.984 ospf_apply_area: instance ptr 0x5efa7aaf4720 64 Oct 4 07:26:51.984 ospf_create_area: : area 0.0.0.0 (0x5efa7ac44850) dummy 0 65 Oct 4 07:26:51.991 ospf_bfd_bind_callback: Bind to BFD server - success 66 Oct 4 07:26:51.991 ospf_bfd_conn_callback: Connection to BFD established 67 Oct 4 07:26:51.991 ospf_bfd_send_create_batch: Batch size 0 68 Oct 4 07:26:51.991 ospf_bfd_end_of_replay: Sending EoR to BFD 69 Oct 4 07:26:51.993 ospf_activate_area_ptr: inactive area, area 0.0.0.0 (0x5efa7ac44850) op_area_count 1 70 Oct 4 07:26:51.993 ospf_activate_area: active area, area 0.0.0.0 71 Oct 4 07:26:51.993 ospf_activate_area: active area, area 0.0.0.0 72 Oct 4 07:26:52.982 ospf_service_redist_summary: Start redist-scanning 73 Oct 4 07:27:00.282 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 74 Oct 4 07:27:00.472 ospf_if_rtr_id_callback: Router-id sent by IPARM: 3.3.3.3 75 Oct 4 07:27:02.177 ospf_cfgmgr_end_of_cfg_cb: Received end of config callback 76 Oct 4 07:27:02.177 ospf_cfgmgr_end_of_cfg_cb: Success 77 Oct 4 07:27:02.178 ospf_nsr_fsm_dispatch_event: dispatching event 30, state 1 -> 1, rev 1 78 Oct 4 07:27:02.178 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 79 Oct 4 07:27:02.178 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 1 80 Oct 4 07:27:02.178 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 81 Oct 4 07:27:02.178 ospf_nsr_fsm_dispatch_event: dispatching event 30, state 1 -> 1, rev 1 82 Oct 4 07:27:02.178 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 83 Oct 4 07:27:02.178 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 1 84 Oct 4 07:27:02.178 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 85 Oct 4 07:27:02.983 ospf_service_redist_summary: Start redist-scanning 86 Oct 4 07:27:06.077 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 87 Oct 4 07:27:12.983 ospf_service_redist_summary: Start redist-scanning 88 Oct 4 07:27:14.123 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 89 Oct 4 07:27:15.916 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 90 Oct 4 07:28:51.785 ospf_nsr_fsm_dispatch_event: dispatching event 22, state 1 -> 1, rev 1 91 Oct 4 07:28:51.785 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 92 Oct 4 07:28:51.785 ospf_nsr_fsm_dispatch_event: dispatching event 22, state 1 -> 1, rev 1 93 Oct 4 07:28:51.785 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 94 Oct 4 07:32:47.181 ospf_clear_stats_instance: 95 Oct 4 07:32:47.181 ospf_clear_interface_stats: clear counters intf Lo0 clear_madj 0 96 Oct 4 07:32:47.181 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 2.2.2.2/10.0.23.2 97 Oct 4 07:32:47.181 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 98 Oct 4 07:32:47.181 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 4.4.4.4/10.0.34.4 99 Oct 4 07:32:47.181 ospf_clear_interface_stats: clear counters intf Gi0/0/0/1 clear_madj 0 100 Oct 4 07:35:57.399 ospf_rtrid_set_configured: new_rtr_id 1.1.1.1 101 Oct 4 07:35:57.399 ospf_rtrid_set_configured: rtrid config by value, op_conf_rtr_id 1.1.1.1 vrfid 60000000 102 Oct 4 07:35:57.399 ospf_soft_reset: start 103 Oct 4 07:35:57.400 ospf_flush_type5: flush external links 104 Oct 4 07:35:57.400 ospf_flush_type11: flush opaque AS links 105 Oct 4 07:35:57.400 ospf_flush_area: flush link states in area 0.0.0.0 106 Oct 4 07:35:57.400 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 107 Oct 4 07:35:57.400 ospf_bfd_delete_one_session: nbr 10.0.23.2 intf Gi0/0/0/0 (area 0.0.0.0), delete BFD session con state 0 108 Oct 4 07:35:57.400 ospf_bfd_delete_one_session: nbr 10.0.34.4 intf Gi0/0/0/1 (area 0.0.0.0), delete BFD session con state 0 109 Oct 4 07:35:57.477 ospf_soft_reset_up: inst 0x5efa7aaf4720 vrfid 0x60000000 delete rid tuple, alloc new rid 110 Oct 4 07:35:57.477 ospf_rtrid_allocate: checking for old rtrid 0x60000000 111 Oct 4 07:35:57.477 ospf_rtrid_allocate: checking for rtrid config 112 Oct 4 07:35:57.477 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set by numeric config: 1.1.1.1 113 Oct 4 07:35:57.482 ospf_soft_reset_up: rtrid: new 1.1.1.1 old 3.3.3.3 114 Oct 4 07:35:57.482 ospf_soft_reset_up: rtrid changed, check vlink for reconfig 115 Oct 4 07:35:58.402 ospf_service_redist_summary: Start redist-scanning 116 Oct 4 07:36:06.513 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 117 Oct 4 07:36:07.330 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 118 Oct 4 07:49:57.483 ospf_clear_stats_instance: 119 Oct 4 07:49:57.483 ospf_clear_interface_stats: clear counters intf Lo0 clear_madj 0 120 Oct 4 07:49:57.483 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 2.2.2.2/10.0.23.2 121 Oct 4 07:49:57.483 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 122 Oct 4 07:49:57.483 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 4.4.4.4/10.0.34.4 123 Oct 4 07:49:57.483 ospf_clear_interface_stats: clear counters intf Gi0/0/0/1 clear_madj 0 124 Oct 4 08:00:01.095 ospf_clear_stats_instance: 125 Oct 4 08:00:01.095 ospf_clear_interface_stats: clear counters intf Lo0 clear_madj 0 126 Oct 4 08:00:01.095 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 2.2.2.2/10.0.23.2 127 Oct 4 08:00:01.095 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 128 Oct 4 08:00:01.095 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 4.4.4.4/10.0.34.4 129 Oct 4 08:00:01.095 ospf_clear_interface_stats: clear counters intf Gi0/0/0/1 clear_madj 0 130 Oct 4 08:04:45.502 ospf_rtrid_set_configured: new_rtr_id 3.3.3.3 131 Oct 4 08:04:45.502 ospf_rtrid_set_configured: rtrid config by value, op_conf_rtr_id 3.3.3.3 vrfid 60000000 132 Oct 4 08:04:45.502 ospf_soft_reset: start 133 Oct 4 08:04:45.502 ospf_flush_type5: flush external links 134 Oct 4 08:04:45.502 ospf_flush_type11: flush opaque AS links 135 Oct 4 08:04:45.502 ospf_flush_area: flush link states in area 0.0.0.0 136 Oct 4 08:04:45.503 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 137 Oct 4 08:04:45.503 ospf_bfd_delete_one_session: nbr 10.0.23.2 intf Gi0/0/0/0 (area 0.0.0.0), delete BFD session con state 0 138 Oct 4 08:04:45.503 ospf_bfd_delete_one_session: nbr 10.0.34.4 intf Gi0/0/0/1 (area 0.0.0.0), delete BFD session con state 0 139 Oct 4 08:04:45.577 ospf_soft_reset_up: inst 0x5efa7aaf4720 vrfid 0x60000000 delete rid tuple, alloc new rid 140 Oct 4 08:04:45.577 ospf_rtrid_allocate: checking for old rtrid 0x60000000 141 Oct 4 08:04:45.577 ospf_rtrid_allocate: checking for rtrid config 142 Oct 4 08:04:45.577 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set by numeric config: 3.3.3.3 143 Oct 4 08:04:45.581 ospf_soft_reset_up: rtrid: new 3.3.3.3 old 1.1.1.1 144 Oct 4 08:04:45.581 ospf_soft_reset_up: rtrid changed, check vlink for reconfig 145 Oct 4 08:04:46.503 ospf_service_redist_summary: Start redist-scanning 146 Oct 4 08:04:54.740 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 147 Oct 4 08:04:55.287 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 148 Oct 4 08:08:55.573 ospf_clear_stats_instance: 149 Oct 4 08:08:55.573 ospf_clear_interface_stats: clear counters intf Lo0 clear_madj 0 150 Oct 4 08:08:55.573 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 2.2.2.2/10.0.23.2 151 Oct 4 08:08:55.573 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 152 Oct 4 08:08:55.573 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 4.4.4.4/10.0.34.4 153 Oct 4 08:08:55.573 ospf_clear_interface_stats: clear counters intf Gi0/0/0/1 clear_madj 0 154 Oct 4 08:12:01.986 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 155 Oct 4 08:12:01.986 ospf_bfd_delete_one_session: nbr 10.0.23.2 intf Gi0/0/0/0 (area 0.0.0.0), delete BFD session con state 0 156 Oct 4 08:12:02.971 ospf_service_maxage: delete MAXAGE lsa, queue size 1 157 Oct 4 08:12:02.971 ospf_service_maxage: processed 1 LSAs from MAXAGE queue, requeued 1, current size 1 158 Oct 4 08:12:03.971 ospf_service_maxage: delete MAXAGE lsa, queue size 1 159 Oct 4 08:12:03.971 ospf_service_maxage: processed 1 LSAs from MAXAGE queue, requeued 1, current size 1 160 Oct 4 08:12:11.890 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 161 Oct 4 08:26:01.479 ospf_clear_stats_instance: 162 Oct 4 08:26:01.480 ospf_clear_interface_stats: clear counters intf Lo0 clear_madj 0 163 Oct 4 08:26:01.480 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 1.1.1.1/10.0.23.2 164 Oct 4 08:26:01.480 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 165 Oct 4 08:26:01.480 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 4.4.4.4/10.0.34.4 166 Oct 4 08:26:01.480 ospf_clear_interface_stats: clear counters intf Gi0/0/0/1 clear_madj 0 167 Oct 4 08:30:50.785 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 168 Oct 4 08:30:50.785 ospf_bfd_delete_one_session: nbr 10.0.23.2 intf Gi0/0/0/0 (area 0.0.0.0), delete BFD session con state 0 169 Oct 4 08:30:51.774 ospf_service_maxage: delete MAXAGE lsa, queue size 1 170 Oct 4 08:30:51.774 ospf_service_maxage: processed 1 LSAs from MAXAGE queue, requeued 1, current size 1 171 Oct 4 08:30:52.774 ospf_service_maxage: delete MAXAGE lsa, queue size 1 172 Oct 4 08:30:52.774 ospf_service_maxage: processed 1 LSAs from MAXAGE queue, requeued 1, current size 1 173 Oct 4 08:31:00.478 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 174 Oct 4 08:35:00.499 ospf_clear_stats_instance: 175 Oct 4 08:35:00.499 ospf_clear_interface_stats: clear counters intf Lo0 clear_madj 0 176 Oct 4 08:35:00.499 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 2.2.2.2/10.0.23.2 177 Oct 4 08:35:00.499 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 178 Oct 4 08:35:00.499 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 4.4.4.4/10.0.34.4 179 Oct 4 08:35:00.499 ospf_clear_interface_stats: clear counters intf Gi0/0/0/1 clear_madj 0 RP/0/RP0/CPU0:R3#show ospf trace errors Sun Oct 4 08:37:08.592 UTC Traces for OSPF 1 (Sun Oct 4 08:37:09) Traces returned/requested/available: 3/8192/3 Trace buffer: errors 1 Oct 4 07:26:51.779 ospf_nsr_ev_free: Cannot free event 0x118dcfc0: flags 0x0,work 0x5efa7ac00aa0, handler 0x5efa3f5b002e 2 Oct 4 07:26:51.779 ospf_nsr_ev_free: inst 0x0, nsr_info 0x0, next 0x0 3 Oct 4 07:26:51.873 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']