RP/0/RP0/CPU0:R1#show ospf trace events Tue Sep 22 00:10:32.002 UTC Traces for OSPF 1 (Tue Sep 22 00:10:32) Traces returned/requested/available: 173/4096/173 Trace buffer: events 1 Sep 21 22:28:33.792 ospf_nsr_event_manager_create: Created channel, ISSU-role 0, HA-role 0 2 Sep 21 22:28:34.799 ospf_if_issu_map_complete_cb: IM issu if mapping complete cb: mapping_complete 0x1 3 Sep 21 22:28:34.800 ospf_get_instance_by_name: : Name unknown in inst AVL name tree 4 Sep 21 22:28:34.800 ospf_create_instance: : instance alloc ptr 0x5f6db84f9c80 5 Sep 21 22:28:34.800 ospf_create_instance: : AVL instance insert ptr 0x5f6db84f9c80 6 Sep 21 22:28:34.800 ospf_create_instance: lock instance 0x0 ptr 0x5f6db84f9c80 refcount 1 7 Sep 21 22:28:34.996 ospf_ds_init: op_issu_id 0 8 Sep 21 22:28:36.493 ospf_create_area: : area 0.0.0.0 (0x5f6db8594370) dummy 1 9 Sep 21 22:28:36.603 ospf_nsr_fsm_dispatch_event: dispatching event 32, state 0 -> 0, rev 0 10 Sep 21 22:28:36.603 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 11 Sep 21 22:28:36.603 ospf_nsr_fsm_dispatch_event: dispatching event 32, state 0 -> 0, rev 0 12 Sep 21 22:28:36.603 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 13 Sep 21 22:28:36.603 ospf_nsr_fsm_dispatch_event: dispatching event 14, state 0 -> 0, rev 0 14 Sep 21 22:28:36.603 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 15 Sep 21 22:28:36.603 ospf_nsr_fsm_dispatch_event: dispatching event 14, state 0 -> 0, rev 0 16 Sep 21 22:28:36.603 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 17 Sep 21 22:28:36.603 ospf_nsr_fsm_dispatch_event: dispatching event 20, state 0 -> 1, rev 0 18 Sep 21 22:28:36.603 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 19 Sep 21 22:28:36.994 ospf_register_end_of_cfg: Attempt to register for end of config notification 20 Sep 21 22:28:37.004 ospf_cfgmgr_end_of_cfg_reg_cb: Received registration callback 21 Sep 21 22:28:37.004 ospf_cfgmgr_end_of_cfg_reg_cb: Registered for end of config 22 Sep 21 22:28:37.005 ospf_register_end_of_cfg: Registration accepted 23 Sep 21 22:28:37.102 ospf_nsr_fsm_dispatch_event: dispatching event 20, state 0 -> 1, rev 0 24 Sep 21 22:28:37.102 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 25 Sep 21 22:28:37.106 ospf_ds_connect_cb: DS Connected 26 Sep 21 22:28:37.106 ospf_publish_ncd_ds_endp: Proc role not yet known 27 Sep 21 22:28:37.106 ospf_register_ncd_ds_service: Proc role not yet known 28 Sep 21 22:28:37.189 ospf_publish_ncd_ds_endp: Proc role not yet known 29 Sep 21 22:28:37.189 ospf_register_ncd_ds_service: Proc role not yet known 30 Sep 21 22:28:37.194 ospf_update_sckt_vrf_opt: vrf opts update OK for vrfid 0x60000000 31 Sep 21 22:28:37.195 ospf_publish_ncd_ds_endp: DS NCD service successfully published, ISSU role 1, HA role 1, 32 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: dispatching event 31, state 1 -> 1, rev 0 33 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 34 Sep 21 22:28:37.195 ospf_register_ncd_ds_service: Successfully registered for NCD DS notification 35 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 0 36 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 37 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: dispatching event 31, state 1 -> 1, rev 0 38 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 39 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 0 40 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 41 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: dispatching event 15, state 1 -> 1, rev 0 42 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 43 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: dispatching event 15, state 1 -> 1, rev 0 44 Sep 21 22:28:37.195 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 45 Sep 21 22:28:37.196 ospf_nsr_msg_chan_update: Update channel, ISSU-role 1, HA-role 1 46 Sep 21 22:28:37.394 ospf_bfd_connect: Attempt to connect to BFD server 47 Sep 21 22:28:37.397 ospf_ds_publish_service_cb: DS NCD service publised succesfully 48 Sep 21 22:28:37.399 ospf_rtrid_set_configured: new_rtr_id 1.1.1.1 49 Sep 21 22:28:37.399 ospf_rtrid_set_configured: rtrid config by value, op_conf_rtr_id 1.1.1.1 vrfid 60000000 50 Sep 21 22:28:37.399 ospf_ds_register_cb: DS NCD service registered succesfully, reg-handle 0x1 51 Sep 21 22:28:37.402 ospf_service_redist_delete: Deleting old redist routes, instance 0x60000000 52 Sep 21 22:28:37.403 ospf_soft_reset: start 53 Sep 21 22:28:37.403 ospf_flush_type5: flush external links 54 Sep 21 22:28:37.403 ospf_flush_type11: flush opaque AS links 55 Sep 21 22:28:37.408 ospf_soft_reset_up: inst 0x5f6db84f9c80 vrfid 0x60000000 delete rid tuple, alloc new rid 56 Sep 21 22:28:37.408 ospf_rtrid_allocate: checking for old rtrid 0x60000000 57 Sep 21 22:28:37.408 ospf_rtrid_allocate: checking for rtrid config 58 Sep 21 22:28:37.408 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set by numeric config: 1.1.1.1 59 Sep 21 22:28:37.490 ospf_soft_reset_up: rtrid: new 1.1.1.1 old 0.0.0.0 60 Sep 21 22:28:37.490 ospf_soft_reset_up: rtrid changed, check vlink for reconfig 61 Sep 21 22:28:37.490 ospf_ds_notify_service_cb: Number of EPs 1 62 Sep 21 22:28:37.490 ospf_ds_notify_service_cb: Setting new DS endp rev 1, number of EPs - 1 63 Sep 21 22:28:37.490 ospf_ncd_ds_endp_update: DS NCD endp, HA-role 1, ISSU-role 1 op_ospf_role 6 64 Sep 21 22:28:37.490 ospf_ncd_ds_endp_update: op_issu_id 0 issu_id 0 65 Sep 21 22:28:37.490 ospf_ncd_ds_endp_update: Ignore DS endp on V1A, ha-role 1, issu-role 1 66 Sep 21 22:28:37.490 ospf_ncd_ds_endp_clean_stale: Cleaning stale NCD endpoints 67 Sep 21 22:28:37.490 ospf_ncd_ds_endp_clean_stale: peer handle not set yet 68 Sep 21 22:28:37.490 ospf_ncd_ds_endp_clean_stale: peer handle not set yet 69 Sep 21 22:28:37.503 ospf_bfd_bind_callback: Bind to BFD server - success 70 Sep 21 22:28:37.503 ospf_bfd_conn_callback: Connection to BFD established 71 Sep 21 22:28:37.503 ospf_bfd_send_create_batch: Batch size 0 72 Sep 21 22:28:37.503 ospf_bfd_end_of_replay: Sending EoR to BFD 73 Sep 21 22:28:37.587 ospf_apply_area: instance ptr 0x5f6db84f9c80 74 Sep 21 22:28:37.587 ospf_create_area: : area 0.0.0.0 (0x5f6db867a980) dummy 0 75 Sep 21 22:28:37.598 ospf_activate_area_ptr: inactive area, area 0.0.0.0 (0x5f6db867a980) op_area_count 1 76 Sep 21 22:28:37.598 ospf_activate_area: active area, area 0.0.0.0 77 Sep 21 22:28:38.403 ospf_service_redist_summary: Start redist-scanning 78 Sep 21 22:28:47.389 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 79 Sep 21 22:28:47.491 ospf_if_rtr_id_callback: Router-id sent by IPARM: 1.1.1.1 80 Sep 21 22:28:48.403 ospf_service_redist_summary: Start redist-scanning 81 Sep 21 22:28:49.095 ospf_cfgmgr_end_of_cfg_cb: Received end of config callback 82 Sep 21 22:28:49.095 ospf_cfgmgr_end_of_cfg_cb: Success 83 Sep 21 22:28:49.095 ospf_nsr_fsm_dispatch_event: dispatching event 30, state 1 -> 1, rev 1 84 Sep 21 22:28:49.095 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 85 Sep 21 22:28:49.095 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 1 86 Sep 21 22:28:49.095 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 87 Sep 21 22:28:49.095 ospf_nsr_fsm_dispatch_event: dispatching event 30, state 1 -> 1, rev 1 88 Sep 21 22:28:49.095 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 89 Sep 21 22:28:49.095 ospf_nsr_fsm_dispatch_event: dispatching event 2, state 1 -> 1, rev 1 90 Sep 21 22:28:49.095 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 91 Sep 21 22:28:52.688 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 92 Sep 21 22:28:58.403 ospf_service_redist_summary: Start redist-scanning 93 Sep 21 22:29:01.959 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 94 Sep 21 22:30:37.195 ospf_nsr_fsm_dispatch_event: dispatching event 22, state 1 -> 1, rev 1 95 Sep 21 22:30:37.195 ospf_nsr_fsm_dispatch_event: NSR-index 0x0 96 Sep 21 22:30:37.195 ospf_nsr_fsm_dispatch_event: dispatching event 22, state 1 -> 1, rev 1 97 Sep 21 22:30:37.195 ospf_nsr_fsm_dispatch_event: NSR-index 0x1 98 Sep 21 22:37:56.201 ospf_clear_stats_instance: 99 Sep 21 22:37:56.201 ospf_clear_interface_stats: clear counters intf Lo0 clear_madj 0 100 Sep 21 22:37:56.201 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 2.2.2.2/10.0.12.2 101 Sep 21 22:37:56.201 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 102 Sep 21 22:42:49.023 ospf_sham_link_check: ext_rt: 172.16.0.0/24 met: 0 ribhdl: 4 103 Sep 21 22:43:04.587 ospf_sham_link_check: ext_rt: 172.16.1.0/24 met: 0 ribhdl: 4 104 Sep 21 22:43:19.987 ospf_sham_link_check: ext_rt: 172.16.2.0/24 met: 0 ribhdl: 4 105 Sep 21 22:43:35.115 ospf_sham_link_check: ext_rt: 172.16.3.0/24 met: 0 ribhdl: 4 106 Sep 21 22:43:48.753 ospf_sham_link_check: ext_rt: 172.16.4.0/24 met: 0 ribhdl: 4 107 Sep 21 22:44:04.820 ospf_sham_link_check: ext_rt: 172.16.5.0/24 met: 0 ribhdl: 4 108 Sep 21 22:44:20.207 ospf_sham_link_check: ext_rt: 172.16.6.0/24 met: 0 ribhdl: 4 109 Sep 21 22:44:33.819 ospf_sham_link_check: ext_rt: 172.16.7.0/24 met: 0 ribhdl: 4 110 Sep 21 22:44:49.732 ospf_sham_link_check: ext_rt: 172.16.8.0/24 met: 0 ribhdl: 4 111 Sep 21 22:45:05.102 ospf_sham_link_check: ext_rt: 172.16.9.0/24 met: 0 ribhdl: 4 112 Sep 21 22:45:19.290 ospf_sham_link_check: ext_rt: 172.16.10.0/24 met: 0 ribhdl: 4 113 Sep 21 22:45:33.416 ospf_sham_link_check: ext_rt: 172.16.11.0/24 met: 0 ribhdl: 4 114 Sep 21 22:45:48.639 ospf_sham_link_check: ext_rt: 172.16.12.0/24 met: 0 ribhdl: 4 115 Sep 21 22:46:03.950 ospf_sham_link_check: ext_rt: 172.16.13.0/24 met: 0 ribhdl: 4 116 Sep 21 22:46:20.342 ospf_sham_link_check: ext_rt: 172.16.14.0/24 met: 0 ribhdl: 4 117 Sep 21 22:46:33.887 ospf_sham_link_check: ext_rt: 172.16.15.0/24 met: 0 ribhdl: 4 118 Sep 21 22:46:49.331 ospf_sham_link_check: ext_rt: 172.16.16.0/24 met: 0 ribhdl: 4 119 Sep 21 22:47:03.502 ospf_sham_link_check: ext_rt: 172.16.17.0/24 met: 0 ribhdl: 4 120 Sep 21 22:47:20.314 ospf_sham_link_check: ext_rt: 172.16.18.0/24 met: 0 ribhdl: 4 121 Sep 21 22:47:35.610 ospf_sham_link_check: ext_rt: 172.16.19.0/24 met: 0 ribhdl: 4 122 Sep 21 22:47:49.692 ospf_sham_link_check: ext_rt: 172.16.20.0/24 met: 0 ribhdl: 4 123 Sep 21 22:48:04.990 ospf_sham_link_check: ext_rt: 172.16.21.0/24 met: 0 ribhdl: 4 124 Sep 21 22:48:20.735 ospf_sham_link_check: ext_rt: 172.16.22.0/24 met: 0 ribhdl: 4 125 Sep 21 22:48:34.308 ospf_sham_link_check: ext_rt: 172.16.23.0/24 met: 0 ribhdl: 4 126 Sep 21 22:48:48.839 ospf_sham_link_check: ext_rt: 172.16.24.0/24 met: 0 ribhdl: 4 127 Sep 21 22:49:04.387 ospf_sham_link_check: ext_rt: 172.16.25.0/24 met: 0 ribhdl: 4 128 Sep 21 22:49:18.912 ospf_sham_link_check: ext_rt: 172.16.26.0/24 met: 0 ribhdl: 4 129 Sep 21 22:49:34.110 ospf_sham_link_check: ext_rt: 172.16.27.0/24 met: 0 ribhdl: 4 130 Sep 21 22:49:48.420 ospf_sham_link_check: ext_rt: 172.16.28.0/24 met: 0 ribhdl: 4 131 Sep 21 22:50:04.624 ospf_sham_link_check: ext_rt: 172.16.29.0/24 met: 0 ribhdl: 4 132 Sep 21 22:50:19.796 ospf_sham_link_check: ext_rt: 172.16.30.0/24 met: 0 ribhdl: 4 133 Sep 21 22:50:34.022 ospf_sham_link_check: ext_rt: 172.16.31.0/24 met: 0 ribhdl: 4 134 Sep 21 22:50:49.110 ospf_sham_link_check: ext_rt: 172.16.32.0/24 met: 0 ribhdl: 4 135 Sep 21 22:51:04.094 ospf_sham_link_check: ext_rt: 172.16.33.0/24 met: 0 ribhdl: 4 136 Sep 21 22:51:19.287 ospf_sham_link_check: ext_rt: 172.16.34.0/24 met: 0 ribhdl: 4 137 Sep 21 22:51:34.018 ospf_sham_link_check: ext_rt: 172.16.35.0/24 met: 0 ribhdl: 4 138 Sep 21 22:51:48.246 ospf_sham_link_check: ext_rt: 172.16.36.0/24 met: 0 ribhdl: 4 139 Sep 21 22:52:03.607 ospf_sham_link_check: ext_rt: 172.16.37.0/24 met: 0 ribhdl: 4 140 Sep 21 22:52:19.198 ospf_sham_link_check: ext_rt: 172.16.38.0/24 met: 0 ribhdl: 4 141 Sep 21 22:52:34.839 ospf_sham_link_check: ext_rt: 172.16.39.0/24 met: 0 ribhdl: 4 142 Sep 21 22:52:48.991 ospf_sham_link_check: ext_rt: 172.16.40.0/24 met: 0 ribhdl: 4 143 Sep 21 22:53:04.691 ospf_sham_link_check: ext_rt: 172.16.41.0/24 met: 0 ribhdl: 4 144 Sep 21 22:53:19.191 ospf_sham_link_check: ext_rt: 172.16.42.0/24 met: 0 ribhdl: 4 145 Sep 21 22:53:33.288 ospf_sham_link_check: ext_rt: 172.16.43.0/24 met: 0 ribhdl: 4 146 Sep 21 22:53:48.627 ospf_sham_link_check: ext_rt: 172.16.44.0/24 met: 0 ribhdl: 4 147 Sep 21 22:54:03.937 ospf_sham_link_check: ext_rt: 172.16.45.0/24 met: 0 ribhdl: 4 148 Sep 21 22:54:19.298 ospf_sham_link_check: ext_rt: 172.16.46.0/24 met: 0 ribhdl: 4 149 Sep 21 22:54:33.505 ospf_sham_link_check: ext_rt: 172.16.47.0/24 met: 0 ribhdl: 4 150 Sep 21 22:54:48.721 ospf_sham_link_check: ext_rt: 172.16.48.0/24 met: 0 ribhdl: 4 151 Sep 21 22:55:04.395 ospf_sham_link_check: ext_rt: 172.16.49.0/24 met: 0 ribhdl: 4 152 Sep 21 22:55:18.699 ospf_sham_link_check: ext_rt: 172.16.50.0/24 met: 0 ribhdl: 4 153 Sep 21 22:55:34.023 ospf_sham_link_check: ext_rt: 172.16.51.0/24 met: 0 ribhdl: 4 154 Sep 21 22:55:49.396 ospf_sham_link_check: ext_rt: 172.16.52.0/24 met: 0 ribhdl: 4 155 Sep 21 22:56:03.786 ospf_sham_link_check: ext_rt: 172.16.53.0/24 met: 0 ribhdl: 4 156 Sep 21 22:56:19.192 ospf_sham_link_check: ext_rt: 172.16.54.0/24 met: 0 ribhdl: 4 157 Sep 21 22:56:33.702 ospf_sham_link_check: ext_rt: 172.16.55.0/24 met: 0 ribhdl: 4 158 Sep 21 22:56:48.893 ospf_sham_link_check: ext_rt: 172.16.56.0/24 met: 0 ribhdl: 4 159 Sep 21 22:57:04.296 ospf_sham_link_check: ext_rt: 172.16.57.0/24 met: 0 ribhdl: 4 160 Sep 21 22:57:18.394 ospf_sham_link_check: ext_rt: 172.16.58.0/24 met: 0 ribhdl: 4 161 Sep 21 22:57:34.017 ospf_sham_link_check: ext_rt: 172.16.59.0/24 met: 0 ribhdl: 4 162 Sep 21 22:59:43.502 ospf_clear_stats_instance: 163 Sep 21 22:59:43.502 ospf_clear_interface_stats: clear counters intf Lo0 clear_madj 0 164 Sep 21 22:59:43.502 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 2.2.2.2/10.0.12.2 165 Sep 21 22:59:43.502 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 166 Sep 21 23:33:43.916 ospf_clear_stats_instance: 167 Sep 21 23:33:43.916 ospf_clear_interface_stats: clear counters intf Lo0 clear_madj 0 168 Sep 21 23:33:43.916 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 2.2.2.2/10.0.12.2 169 Sep 21 23:33:43.916 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 170 Sep 22 00:09:40.807 ospf_clear_stats_instance: 171 Sep 22 00:09:40.807 ospf_clear_interface_stats: clear counters intf Lo0 clear_madj 0 172 Sep 22 00:09:40.807 ospf_clear_neighbor_events: reset nbr_event_count [2], nbr 2.2.2.2/10.0.12.2 173 Sep 22 00:09:40.807 ospf_clear_interface_stats: clear counters intf Gi0/0/0/0 clear_madj 0 RP/0/RP0/CPU0:R1#show ospf trace errors Tue Sep 22 00:10:32.806 UTC Traces for OSPF 1 (Tue Sep 22 00:10:33) Traces returned/requested/available: 3/8192/3 Trace buffer: errors 1 Sep 21 22:28:37.188 ospf_nsr_ev_free: Cannot free event 0xfc871fc0: flags 0x0,work 0x5f6db86079a0, handler 0x5f6db23e102e 2 Sep 21 22:28:37.188 ospf_nsr_ev_free: inst 0x0, nsr_info 0x0, next 0x0 3 Sep 21 22:28:37.204 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'] RP/0/RP0/CPU0:R1#show ospf trace all | include refresh Tue Sep 22 00:10:33.598 UTC RP/0/RP0/CPU0:R1#show ospf trace all | include pacing Tue Sep 22 00:10:51.326 UTC 49 Sep 21 22:42:41.725 ospf_apply_lsapacing_timer: sense 1 op_lsa_group_interval 240 new_interval 10 52 Sep 21 23:38:24.744 ospf_apply_lsapacing_timer: sense 1 op_lsa_group_interval 10 new_interval 240 RP/0/RP0/CPU0:R1#show ospf trace all | include flood Tue Sep 22 00:11:08.025 UTC