RP/0/RP0/CPU0:R1#show ospf trace spf | include Begin SPF Wed Sep 16 11:27:10.869 UTC 22 Sep 16 11:21:35.460 ospf_run_spf: Begin SPF 53 Sep 16 11:21:44.716 ospf_run_spf: Begin SPF 84 Sep 16 11:21:49.567 ospf_run_spf: Begin SPF 116 Sep 16 11:21:59.187 ospf_run_spf: Begin SPF 145 Sep 16 11:21:59.388 ospf_run_spf: Begin SPF 176 Sep 16 11:22:05.490 ospf_run_spf: Begin SPF 207 Sep 16 11:22:10.203 ospf_run_spf: Begin SPF RP/0/RP0/CPU0:R1#show ospf trace adj_cycle Wed Sep 16 11:27:12.276 UTC Traces for OSPF 1 (Wed Sep 16 11:27:13) Traces returned/requested/available: 20/8192/20 Trace buffer: adj_cycle 1 Sep 16 11:21:59.120 ospf_rcv_dbd: from 2.2.2.2(10.0.12.2) intf Gi0/0/0/0 seq 0x2c6b5d9b 2 Sep 16 11:21:59.120 ospf_send_dbd: to 2.2.2.2(10.0.12.2) intf Gi0/0/0/0 seq 0x156cd2c1 3 Sep 16 11:21:59.120 ospf_send_dbd: to 2.2.2.2(10.0.12.2) intf Gi0/0/0/0 seq 0x2c6b5d9b 4 Sep 16 11:21:59.128 ospf_rcv_dbd: from 2.2.2.2(10.0.12.2) intf Gi0/0/0/0 seq 0x2c6b5d9c 5 Sep 16 11:21:59.128 ospf_output: LSA pkt, src 10.0.12.1 dest 10.0.12.2 area 0.0.0.0 intf Gi0/0/0/0 6 Sep 16 11:21:59.129 ospf_send_req: LS REQ pkt, nbr 2.2.2.2/10.0.12.2 cnt 1 unsent 0 7 Sep 16 11:21:59.129 ospf_send_dbd: to 2.2.2.2(10.0.12.2) intf Gi0/0/0/0 seq 0x2c6b5d9c 8 Sep 16 11:21:59.136 ospf_do_hello_packets: LSA pkt, src 10.0.12.2 intf Gi0/0/0/0 type 4 paksize 178 9 Sep 16 11:21:59.136 ospf_do_hello_packets: LSA pkt, src 10.0.12.2 intf Gi0/0/0/0 type 3 paksize 126 10 Sep 16 11:21:59.136 ospf_output: LSA pkt, src 10.0.12.1 dest 10.0.12.2 area 0.0.0.0 intf Gi0/0/0/0 11 Sep 16 11:21:59.189 ospf_output: LSA pkt, src 10.0.12.1 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/0 12 Sep 16 11:21:59.196 ospf_do_hello_packets: LSA pkt, src 10.0.12.2 intf Gi0/0/0/0 type 4 paksize 190 13 Sep 16 11:22:01.136 ospf_output: LSA pkt, src 10.0.12.1 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/0 14 Sep 16 11:22:01.144 ospf_do_hello_packets: LSA pkt, src 10.0.12.2 intf Gi0/0/0/0 type 5 paksize 134 15 Sep 16 11:22:05.438 ospf_do_hello_packets: LSA pkt, src 10.0.12.2 intf Gi0/0/0/0 type 4 paksize 166 16 Sep 16 11:22:05.488 ospf_do_hello_packets: LSA pkt, src 10.0.12.2 intf Gi0/0/0/0 type 4 paksize 202 17 Sep 16 11:22:05.522 ospf_do_hello_packets: LSA pkt, src 10.0.12.2 intf Gi0/0/0/0 type 4 paksize 178 18 Sep 16 11:22:07.439 ospf_output: LSA pkt, src 10.0.12.1 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/0 19 Sep 16 11:22:10.153 ospf_do_hello_packets: LSA pkt, src 10.0.12.2 intf Gi0/0/0/0 type 4 paksize 178 20 Sep 16 11:22:12.153 ospf_output: LSA pkt, src 10.0.12.1 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/0 RP/0/RP0/CPU0:R1#show ospf trace events | include lsa Wed Sep 16 11:27:13.634 UTC 78 Sep 16 11:21:44.662 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 91 Sep 16 11:21:49.464 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 93 Sep 16 11:21:59.135 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 RP/0/RP0/CPU0:R1#show ospf trace errors Wed Sep 16 11:27:14.812 UTC Traces for OSPF 1 (Wed Sep 16 11:27:15) Traces returned/requested/available: 3/8192/3 Trace buffer: errors 1 Sep 16 11:21:35.063 ospf_nsr_ev_free: Cannot free event 0x3fd93fc0: flags 0x0,work 0x615d9add39b0, handler 0x615d8261e02e 2 Sep 16 11:21:35.063 ospf_nsr_ev_free: inst 0x0, nsr_info 0x0, next 0x0 3 Sep 16 11:21:35.079 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 rtr Wed Sep 16 11:27:15.768 UTC 3 Sep 16 11:21:35.184 ospf_build_ex_lsa: no rtrid to build external LSA 6 Sep 16 11:21:44.665 ospf_build_rtr_lsa: area 0.0.0.0 rtrid 1.1.1.1 seq 0x80000001 vrfid 0x60000000 9 Sep 16 11:21:49.517 ospf_build_rtr_lsa: area 0.0.0.0 rtrid 1.1.1.1 seq 0x80000002 vrfid 0x60000000 41 Sep 16 11:21:59.189 ospf_build_rtr_lsa: area 0.0.0.0 rtrid 1.1.1.1 seq 0x80000003 vrfid 0x60000000 42 Sep 16 11:22:05.522 ospf_rcv_update: Min Arrival time check fail 3.3.3.3 type 1 Adv rtr 3.3.3.3 vrfid 0x60000000 5 Sep 16 11:21:35.182 ospf_apply_routerid: rtrid 1.1.1.1 6 Sep 16 11:21:35.182 ospf_apply_routerid: soft reset, op_rtr_id 0.0.0.0 new 1.1.1.1 48 Sep 16 11:21:35.182 ospf_rtrid_set_configured: new_rtr_id 1.1.1.1 49 Sep 16 11:21:35.182 ospf_rtrid_set_configured: rtrid config by value, op_conf_rtr_id 1.1.1.1 vrfid 60000000 56 Sep 16 11:21:35.262 ospf_rtrid_allocate: checking for old rtrid 0x60000000 57 Sep 16 11:21:35.262 ospf_rtrid_allocate: checking for rtrid config 58 Sep 16 11:21:35.262 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set by numeric config: 1.1.1.1 59 Sep 16 11:21:35.266 ospf_soft_reset_up: rtrid: new 1.1.1.1 old 0.0.0.0 60 Sep 16 11:21:35.266 ospf_soft_reset_up: rtrid changed, check vlink for reconfig 78 Sep 16 11:21:44.663 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 79 Sep 16 11:21:44.764 ospf_if_rtr_id_callback: Router-id sent by IPARM: 1.1.1.1 91 Sep 16 11:21:49.465 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 93 Sep 16 11:21:59.136 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 18 Sep 16 11:21:31.666 ospf_nsr_init: Init init-sync continue (rtr thd) timer 132 Sep 16 11:21:32.959 ospf_nsr_init_rtr_thd_timers: Init init-sync continue timer 133 Sep 16 11:21:32.959 ospf_nsr_init_rtr_thd_timers: Init LSA async batching timer 134 Sep 16 11:21:32.959 ospf_nsr_init_rtr_thd_timers: Init LSA sync batching timer 135 Sep 16 11:21:32.959 ospf_nsr_init_rtr_thd_timers: Init LSA batching timer 136 Sep 16 11:21:32.959 ospf_nsr_init_rtr_thd_timers: Init init-sync continue timer 137 Sep 16 11:21:32.959 ospf_nsr_init_rtr_thd_timers: Init LSA async batching timer 138 Sep 16 11:21:32.959 ospf_nsr_init_rtr_thd_timers: Init LSA sync batching timer 139 Sep 16 11:21:32.959 ospf_nsr_init_rtr_thd_timers: Init LSA batching timer 343 Sep 16 11:21:35.063 ospf_aux_rtr_thd_rsi_conn_up_handler: Rcv RSI connection UP in Router thread 346 Sep 16 11:21:35.065 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(6), event(1) 347 Sep 16 11:21:35.065 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(2), event(1) 348 Sep 16 11:21:35.065 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(1), event(1) 421 Sep 16 11:21:35.179 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(5), event(1) 456 Sep 16 11:21:46.562 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(4), event(1) 2 Sep 16 11:21:35.063 ospf_rsi_rtr_thd_if_rescan: Added 0 idbs to the router thread batch 23 Sep 16 11:21:35.463 ospf_aux_rtr_thd_srlg_callback_handler_per_idb: Handling SRLG callback for Gi0/0/0/0 area 0.0.0.0, event 0x0, SRLG handle 0x7d500c000e80 24 Sep 16 11:21:35.463 ospf_aux_rtr_thd_srlg_result_handler: Rcv SRLG result OK for Gi0/0/0/0, old SRLG handle 0x7d500c000e80, new SRLG handle 0x7d500c000e80 6 Sep 16 11:21:35.266 ospf_te_set_ospf_rtrid: TE system-id 1.1.1.1 4 Sep 16 11:21:44.665 ospf_build_rtr_lsa: FC: SCHED_SPF: ar 0.0.0.0 (vrf 0x60000000, seq 0x80000001) - Router LSA changed 8 Sep 16 11:21:49.467 ospf_build_rtr_lsa: FC: T_DELAY_RTR_LSA: ar 0.0.0.0, bucket: 11,35 type 2 9 Sep 16 11:21:49.517 ospf_build_rtr_lsa: FC: SCHED_SPF: ar 0.0.0.0 (vrf 0x60000000, seq 0x80000002) - Router LSA changed 12 Sep 16 11:21:59.136 db_install: FC: LSA_CHG: New Rtr LSA: ar 0.0.0.0 lsid 2.2.2.2 advrtr 2.2.2.2 seq 0x80000002 15 Sep 16 11:21:59.136 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 2.2.2.2 advrtr 2.2.2.2 16 Sep 16 11:21:59.138 ospf_build_rtr_lsa: FC: T_DELAY_RTR_LSA: ar 0.0.0.0, bucket: 11,35 type 2 19 Sep 16 11:21:59.189 ospf_build_rtr_lsa: FC: SCHED_SPF: ar 0.0.0.0 (vrf 0x60000000, seq 0x80000003) - Router LSA changed 20 Sep 16 11:21:59.196 db_install: FC: LSA_CHG: Changed Rtr LSA: ar 0.0.0.0 lsid 2.2.2.2 advrtr 2.2.2.2 seq 0x80000003 21 Sep 16 11:21:59.196 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 2.2.2.2 advrtr 2.2.2.2 26 Sep 16 11:22:05.438 db_install: FC: LSA_CHG: New Rtr LSA: ar 0.0.0.0 lsid 3.3.3.3 advrtr 3.3.3.3 seq 0x80000002 27 Sep 16 11:22:05.438 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 3.3.3.3 advrtr 3.3.3.3 28 Sep 16 11:22:05.488 db_install: FC: LSA_CHG: Changed Rtr LSA: ar 0.0.0.0 lsid 2.2.2.2 advrtr 2.2.2.2 seq 0x80000004 29 Sep 16 11:22:05.488 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 2.2.2.2 advrtr 2.2.2.2 32 Sep 16 11:22:10.153 db_install: FC: LSA_CHG: Changed Rtr LSA: ar 0.0.0.0 lsid 3.3.3.3 advrtr 3.3.3.3 seq 0x80000003 33 Sep 16 11:22:10.153 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 3.3.3.3 advrtr 3.3.3.3 11 Sep 16 11:21:35.062 ospf_iparm_rtrid_cb: rtr ID current = 0.0.0.0 new = 0.0.0.0 18 Sep 16 11:21:35.067 ospf_aux_rtr_thd_vrf_callback_handler: VRF id 0x60000000 and table_id 0xe0000000 instance 0x615d9acc5c80 event 0 19 Sep 16 11:21:35.069 ospf_aux_rtr_thd_vrf_result_handler: Rcv VRF Registration result OK for instance 0x615d9acc5c80 vrf handle 0xc000e00 event 0x0 20 Sep 16 11:21:35.069 ospf_aux_rtr_thd_vrf_result_handler: update VRF result handle to valid instance 0x615d9acc5c80 122 Sep 16 11:21:44.764 ospf_iparm_rtrid_cb: rtr ID current = 0.0.0.0 new = 1.1.1.1 123 Sep 16 11:21:44.764 ospf_iparm_rtrid_cb: rtr ID current = 1.1.1.1 new = 1.1.1.1 RP/0/RP0/CPU0:R1#show ospf trace all | include db_install Wed Sep 16 11:27:34.695 UTC 12 Sep 16 11:21:59.136 db_install: FC: LSA_CHG: New Rtr LSA: ar 0.0.0.0 lsid 2.2.2.2 advrtr 2.2.2.2 seq 0x80000002 15 Sep 16 11:21:59.136 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 2.2.2.2 advrtr 2.2.2.2 20 Sep 16 11:21:59.196 db_install: FC: LSA_CHG: Changed Rtr LSA: ar 0.0.0.0 lsid 2.2.2.2 advrtr 2.2.2.2 seq 0x80000003 21 Sep 16 11:21:59.196 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 2.2.2.2 advrtr 2.2.2.2 26 Sep 16 11:22:05.438 db_install: FC: LSA_CHG: New Rtr LSA: ar 0.0.0.0 lsid 3.3.3.3 advrtr 3.3.3.3 seq 0x80000002 27 Sep 16 11:22:05.438 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 3.3.3.3 advrtr 3.3.3.3 28 Sep 16 11:22:05.488 db_install: FC: LSA_CHG: Changed Rtr LSA: ar 0.0.0.0 lsid 2.2.2.2 advrtr 2.2.2.2 seq 0x80000004 29 Sep 16 11:22:05.488 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 2.2.2.2 advrtr 2.2.2.2 32 Sep 16 11:22:10.153 db_install: FC: LSA_CHG: Changed Rtr LSA: ar 0.0.0.0 lsid 3.3.3.3 advrtr 3.3.3.3 seq 0x80000003 33 Sep 16 11:22:10.153 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 3.3.3.3 advrtr 3.3.3.3 RP/0/RP0/CPU0:R1#show ospf trace all | include arriv Wed Sep 16 11:27:52.969 UTC RP/0/RP0/CPU0:R1#show ospf trace spf Wed Sep 16 11:28:13.496 UTC Traces for OSPF 1 (Wed Sep 16 11:28:14) Traces returned/requested/available: 237/8192/237 Trace buffer: spf 1 Sep 16 11:21:35.174 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 2 Sep 16 11:21:35.174 ospf_rib_ipv4_update_done: running rt cnt 0 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x0 3 Sep 16 11:21:35.174 ospf_update_done_cb: SPF complete: Elapse time: 1013 ms. 4 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 5 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 6 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 7 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 8 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 9 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 10 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 11 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 12 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 13 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 14 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 15 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 16 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 17 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 18 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 19 Sep 16 11:21:35.174 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 20 Sep 16 11:21:35.259 ospf_force_spf: flag 0 21 Sep 16 11:21:35.364 ospf_force_spf: flag 0 22 Sep 16 11:21:35.460 ospf_run_spf: Begin SPF 23 Sep 16 11:21:35.460 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 24 Sep 16 11:21:35.460 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 25 Sep 16 11:21:35.460 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 26 Sep 16 11:21:35.460 delete_old_routes: area 0.0.0.0 27 Sep 16 11:21:35.460 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 28 Sep 16 11:21:35.460 ospf_run_spf: SPF Dijkstra TRUE 29 Sep 16 11:21:35.460 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 30 Sep 16 11:21:35.460 ospf_rib_ipv4_update_done: running rt cnt 0 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 31 Sep 16 11:21:35.460 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 32 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 33 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 34 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 35 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 36 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 37 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 38 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 39 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 40 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 41 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 42 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 43 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 44 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 45 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 46 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 47 Sep 16 11:21:35.460 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 48 Sep 16 11:21:35.460 ospf_run_spf: SPF Processing ends 49 Sep 16 11:21:35.460 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 50 Sep 16 11:21:35.460 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 51 Sep 16 11:21:35.460 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 52 Sep 16 11:21:35.460 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 53 Sep 16 11:21:44.716 ospf_run_spf: Begin SPF 54 Sep 16 11:21:44.716 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 55 Sep 16 11:21:44.716 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 56 Sep 16 11:21:44.716 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 57 Sep 16 11:21:44.716 delete_old_routes: area 0.0.0.0 58 Sep 16 11:21:44.716 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 59 Sep 16 11:21:44.716 ospf_run_spf: SPF Dijkstra TRUE 60 Sep 16 11:21:44.716 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 61 Sep 16 11:21:44.716 ospf_rib_ipv4_update_done: running rt cnt 0 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 62 Sep 16 11:21:44.716 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 63 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 64 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 65 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 66 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 67 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 68 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 69 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 70 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 71 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 72 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 73 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 74 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 75 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 76 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 77 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 78 Sep 16 11:21:44.716 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 79 Sep 16 11:21:44.716 ospf_run_spf: SPF Processing ends 80 Sep 16 11:21:44.716 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 81 Sep 16 11:21:44.716 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 82 Sep 16 11:21:44.716 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 83 Sep 16 11:21:44.716 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 84 Sep 16 11:21:49.567 ospf_run_spf: Begin SPF 85 Sep 16 11:21:49.567 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 86 Sep 16 11:21:49.567 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 87 Sep 16 11:21:49.567 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 88 Sep 16 11:21:49.568 delete_old_routes: area 0.0.0.0 89 Sep 16 11:21:49.568 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 90 Sep 16 11:21:49.568 ospf_run_spf: SPF Dijkstra TRUE 91 Sep 16 11:21:49.568 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 92 Sep 16 11:21:49.568 ospf_rib_ipv4_update_done: running rt cnt 0 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 93 Sep 16 11:21:49.568 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 94 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 95 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 96 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 97 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 98 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 99 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 100 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 101 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 102 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 103 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 104 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 105 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 106 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 107 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 108 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 109 Sep 16 11:21:49.568 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 110 Sep 16 11:21:49.568 ospf_run_spf: SPF Processing ends 111 Sep 16 11:21:49.568 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 112 Sep 16 11:21:49.568 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 113 Sep 16 11:21:49.568 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 114 Sep 16 11:21:49.568 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 115 Sep 16 11:21:59.136 ospf_force_spf: flag 0 116 Sep 16 11:21:59.187 ospf_run_spf: Begin SPF 117 Sep 16 11:21:59.187 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 118 Sep 16 11:21:59.187 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 119 Sep 16 11:21:59.187 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 120 Sep 16 11:21:59.187 delete_old_routes: area 0.0.0.0 121 Sep 16 11:21:59.187 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 122 Sep 16 11:21:59.187 ospf_run_spf: SPF Dijkstra TRUE 123 Sep 16 11:21:59.187 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 124 Sep 16 11:21:59.187 ospf_rib_ipv4_update_done: running rt cnt 0 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 125 Sep 16 11:21:59.187 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 126 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 127 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 128 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 129 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 130 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 131 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 132 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 133 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 134 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 135 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 136 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 137 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 138 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 139 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 140 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 141 Sep 16 11:21:59.187 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 142 Sep 16 11:21:59.187 ospf_run_spf: SPF Processing ends 143 Sep 16 11:21:59.187 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 144 Sep 16 11:21:59.187 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 145 Sep 16 11:21:59.388 ospf_run_spf: Begin SPF 146 Sep 16 11:21:59.388 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 147 Sep 16 11:21:59.388 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 148 Sep 16 11:21:59.388 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 149 Sep 16 11:21:59.388 delete_old_routes: area 0.0.0.0 150 Sep 16 11:21:59.388 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 1 lfa cnt 0 (inst 0x60000000) 151 Sep 16 11:21:59.388 ospf_run_spf: SPF Dijkstra TRUE 152 Sep 16 11:21:59.388 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 1 lfa cnt 0 (inst 0x60000000) 153 Sep 16 11:21:59.388 ospf_rib_ipv4_update_done: running rt cnt 2 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 154 Sep 16 11:21:59.388 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 155 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 156 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 157 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 158 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 159 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 160 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 161 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 162 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 163 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats : level 4: batches: 1 routes: 1 164 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats, paths: add 1 err 0, del 0 err 0 165 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 166 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 167 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats : level 6: batches: 1 routes: 1 168 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats, paths: add 1 err 0, del 0 err 0 169 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 170 Sep 16 11:21:59.388 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 171 Sep 16 11:21:59.388 ospf_run_spf: SPF Processing ends 172 Sep 16 11:21:59.388 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 173 Sep 16 11:21:59.388 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 174 Sep 16 11:21:59.388 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 175 Sep 16 11:21:59.388 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 176 Sep 16 11:22:05.490 ospf_run_spf: Begin SPF 177 Sep 16 11:22:05.490 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 178 Sep 16 11:22:05.490 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 179 Sep 16 11:22:05.490 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 180 Sep 16 11:22:05.490 delete_old_routes: area 0.0.0.0 181 Sep 16 11:22:05.490 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 182 Sep 16 11:22:05.490 ospf_run_spf: SPF Dijkstra TRUE 183 Sep 16 11:22:05.490 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 184 Sep 16 11:22:05.490 ospf_rib_ipv4_update_done: running rt cnt 0 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 185 Sep 16 11:22:05.490 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 186 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 187 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 188 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 189 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 190 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 191 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 192 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 193 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 194 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats : level 4: batches: 1 routes: 1 195 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats, paths: add 1 err 0, del 0 err 0 196 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 197 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 198 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats : level 6: batches: 1 routes: 1 199 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats, paths: add 1 err 0, del 0 err 0 200 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 201 Sep 16 11:22:05.490 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 202 Sep 16 11:22:05.490 ospf_run_spf: SPF Processing ends 203 Sep 16 11:22:05.490 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 204 Sep 16 11:22:05.490 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 205 Sep 16 11:22:05.490 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 206 Sep 16 11:22:05.490 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 207 Sep 16 11:22:10.203 ospf_run_spf: Begin SPF 208 Sep 16 11:22:10.203 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 209 Sep 16 11:22:10.203 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 210 Sep 16 11:22:10.203 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 211 Sep 16 11:22:10.204 delete_old_routes: area 0.0.0.0 212 Sep 16 11:22:10.204 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 1 lfa cnt 0 (inst 0x60000000) 213 Sep 16 11:22:10.204 ospf_run_spf: SPF Dijkstra TRUE 214 Sep 16 11:22:10.204 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 1 lfa cnt 0 (inst 0x60000000) 215 Sep 16 11:22:10.204 ospf_rib_ipv4_update_done: running rt cnt 1 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 216 Sep 16 11:22:10.204 ospf_update_done_cb: SPF complete: Elapse time: 1 ms. 217 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 218 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 219 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 220 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 221 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 222 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 223 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 224 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 225 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats : level 4: batches: 2 routes: 2 226 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats, paths: add 2 err 0, del 0 err 0 227 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 228 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 229 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats : level 6: batches: 2 routes: 1 230 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats, paths: add 1 err 0, del 0 err 0 231 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 232 Sep 16 11:22:10.204 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 233 Sep 16 11:22:10.204 ospf_run_spf: SPF Processing ends 234 Sep 16 11:22:10.204 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 235 Sep 16 11:22:10.204 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 236 Sep 16 11:22:10.204 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 237 Sep 16 11:22:10.204 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000)