RP/0/RP0/CPU0:R3#show ospf trace spf | include Begin SPF Wed Sep 16 11:30:30.526 UTC 22 Sep 16 11:21:47.618 ospf_run_spf: Begin SPF 53 Sep 16 11:21:56.415 ospf_run_spf: Begin SPF 84 Sep 16 11:22:01.916 ospf_run_spf: Begin SPF 116 Sep 16 11:22:05.487 ospf_run_spf: Begin SPF 145 Sep 16 11:22:05.887 ospf_run_spf: Begin SPF RP/0/RP0/CPU0:R3#show ospf trace adj_cycle Wed Sep 16 11:30:31.419 UTC Traces for OSPF 1 (Wed Sep 16 11:30:32) Traces returned/requested/available: 14/8192/14 Trace buffer: adj_cycle 1 Sep 16 11:22:05.399 ospf_send_dbd: to 2.2.2.2(10.0.23.2) intf Gi0/0/0/0 seq 0x10bc4300 2 Sep 16 11:22:05.422 ospf_rcv_dbd: from 2.2.2.2(10.0.23.2) intf Gi0/0/0/0 seq 0x27d2308d 3 Sep 16 11:22:05.422 ospf_rcv_dbd: from 2.2.2.2(10.0.23.2) intf Gi0/0/0/0 seq 0x10bc4300 4 Sep 16 11:22:05.422 ospf_send_dbd: to 2.2.2.2(10.0.23.2) intf Gi0/0/0/0 seq 0x10bc4301 5 Sep 16 11:22:05.429 ospf_do_hello_packets: LSA pkt, src 10.0.23.2 intf Gi0/0/0/0 type 3 paksize 126 6 Sep 16 11:22:05.429 ospf_output: LSA pkt, src 10.0.23.3 dest 10.0.23.2 area 0.0.0.0 intf Gi0/0/0/0 7 Sep 16 11:22:05.429 ospf_rcv_dbd: from 2.2.2.2(10.0.23.2) intf Gi0/0/0/0 seq 0x10bc4301 8 Sep 16 11:22:05.429 ospf_output: LSA pkt, src 10.0.23.3 dest 10.0.23.2 area 0.0.0.0 intf Gi0/0/0/0 9 Sep 16 11:22:05.429 ospf_send_req: LS REQ pkt, nbr 2.2.2.2/10.0.23.2 cnt 2 unsent 0 10 Sep 16 11:22:05.436 ospf_do_hello_packets: LSA pkt, src 10.0.23.2 intf Gi0/0/0/0 type 4 paksize 250 11 Sep 16 11:22:05.488 ospf_do_hello_packets: LSA pkt, src 10.0.23.2 intf Gi0/0/0/0 type 4 paksize 202 12 Sep 16 11:22:05.490 ospf_output: LSA pkt, src 10.0.23.3 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/0 13 Sep 16 11:22:07.436 ospf_do_hello_packets: LSA pkt, src 10.0.23.2 intf Gi0/0/0/0 type 5 paksize 134 14 Sep 16 11:22:07.436 ospf_output: LSA pkt, src 10.0.23.3 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/0 RP/0/RP0/CPU0:R3#show ospf trace events | include lsa Wed Sep 16 11:30:32.155 UTC 78 Sep 16 11:21:56.320 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 91 Sep 16 11:22:01.730 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 92 Sep 16 11:22:05.436 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 RP/0/RP0/CPU0:R3#show ospf trace errors Wed Sep 16 11:30:33.127 UTC Traces for OSPF 1 (Wed Sep 16 11:30:33) Traces returned/requested/available: 3/8192/3 Trace buffer: errors 1 Sep 16 11:21:47.026 ospf_nsr_ev_free: Cannot free event 0x38019fc0: flags 0x0,work 0x5a51b0b06900, handler 0x5a5173da802e 2 Sep 16 11:21:47.026 ospf_nsr_ev_free: inst 0x0, nsr_info 0x0, next 0x0 3 Sep 16 11:21:47.121 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:R3#show ospf trace all | include rtr Wed Sep 16 11:30:34.150 UTC 3 Sep 16 11:21:47.322 ospf_build_ex_lsa: no rtrid to build external LSA 6 Sep 16 11:21:56.322 ospf_build_rtr_lsa: area 0.0.0.0 rtrid 3.3.3.3 seq 0x80000001 vrfid 0x60000000 9 Sep 16 11:22:01.865 ospf_build_rtr_lsa: area 0.0.0.0 rtrid 3.3.3.3 seq 0x80000002 vrfid 0x60000000 40 Sep 16 11:22:05.490 ospf_build_rtr_lsa: area 0.0.0.0 rtrid 3.3.3.3 seq 0x80000003 vrfid 0x60000000 5 Sep 16 11:21:47.320 ospf_apply_routerid: rtrid 3.3.3.3 6 Sep 16 11:21:47.320 ospf_apply_routerid: soft reset, op_rtr_id 0.0.0.0 new 3.3.3.3 48 Sep 16 11:21:47.320 ospf_rtrid_set_configured: new_rtr_id 3.3.3.3 49 Sep 16 11:21:47.320 ospf_rtrid_set_configured: rtrid config by value, op_conf_rtr_id 3.3.3.3 vrfid 60000000 56 Sep 16 11:21:47.328 ospf_rtrid_allocate: checking for old rtrid 0x60000000 57 Sep 16 11:21:47.328 ospf_rtrid_allocate: checking for rtrid config 58 Sep 16 11:21:47.328 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set by numeric config: 3.3.3.3 59 Sep 16 11:21:47.420 ospf_soft_reset_up: rtrid: new 3.3.3.3 old 0.0.0.0 60 Sep 16 11:21:47.420 ospf_soft_reset_up: rtrid changed, check vlink for reconfig 78 Sep 16 11:21:56.320 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 79 Sep 16 11:21:56.420 ospf_if_rtr_id_callback: Router-id sent by IPARM: 3.3.3.3 91 Sep 16 11:22:01.730 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 92 Sep 16 11:22:05.436 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 18 Sep 16 11:21:43.728 ospf_nsr_init: Init init-sync continue (rtr thd) timer 132 Sep 16 11:21:45.124 ospf_nsr_init_rtr_thd_timers: Init init-sync continue timer 133 Sep 16 11:21:45.124 ospf_nsr_init_rtr_thd_timers: Init LSA async batching timer 134 Sep 16 11:21:45.124 ospf_nsr_init_rtr_thd_timers: Init LSA sync batching timer 135 Sep 16 11:21:45.124 ospf_nsr_init_rtr_thd_timers: Init LSA batching timer 136 Sep 16 11:21:45.124 ospf_nsr_init_rtr_thd_timers: Init init-sync continue timer 137 Sep 16 11:21:45.124 ospf_nsr_init_rtr_thd_timers: Init LSA async batching timer 138 Sep 16 11:21:45.124 ospf_nsr_init_rtr_thd_timers: Init LSA sync batching timer 139 Sep 16 11:21:45.124 ospf_nsr_init_rtr_thd_timers: Init LSA batching timer 343 Sep 16 11:21:47.025 ospf_aux_rtr_thd_rsi_conn_up_handler: Rcv RSI connection UP in Router thread 346 Sep 16 11:21:47.026 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(6), event(1) 347 Sep 16 11:21:47.026 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(2), event(1) 348 Sep 16 11:21:47.026 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(1), event(1) 421 Sep 16 11:21:47.319 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(5), event(1) 456 Sep 16 11:21:58.028 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(4), event(1) 2 Sep 16 11:21:47.025 ospf_rsi_rtr_thd_if_rescan: Added 0 idbs to the router thread batch 23 Sep 16 11:21:47.621 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 0x7075d8000e80 24 Sep 16 11:21:47.621 ospf_aux_rtr_thd_srlg_result_handler: Rcv SRLG result OK for Gi0/0/0/0, old SRLG handle 0x7075d8000e80, new SRLG handle 0x7075d8000e80 6 Sep 16 11:21:47.420 ospf_te_set_ospf_rtrid: TE system-id 3.3.3.3 4 Sep 16 11:21:56.322 ospf_build_rtr_lsa: FC: SCHED_SPF: ar 0.0.0.0 (vrf 0x60000000, seq 0x80000001) - Router LSA changed 8 Sep 16 11:22:01.815 ospf_build_rtr_lsa: FC: T_DELAY_RTR_LSA: ar 0.0.0.0, bucket: 11,35 type 2 9 Sep 16 11:22:01.865 ospf_build_rtr_lsa: FC: SCHED_SPF: ar 0.0.0.0 (vrf 0x60000000, seq 0x80000002) - Router LSA changed 12 Sep 16 11:22:05.436 db_install: FC: LSA_CHG: New Rtr LSA: ar 0.0.0.0 lsid 1.1.1.1 advrtr 1.1.1.1 seq 0x80000003 13 Sep 16 11:22:05.436 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 1.1.1.1 advrtr 1.1.1.1 14 Sep 16 11:22:05.436 db_install: FC: LSA_CHG: New Rtr LSA: ar 0.0.0.0 lsid 2.2.2.2 advrtr 2.2.2.2 seq 0x80000003 17 Sep 16 11:22:05.436 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 2.2.2.2 advrtr 2.2.2.2 18 Sep 16 11:22:05.438 ospf_build_rtr_lsa: FC: T_DELAY_RTR_LSA: ar 0.0.0.0, bucket: 11,35 type 2 21 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 22 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 23 Sep 16 11:22:05.490 ospf_build_rtr_lsa: FC: SCHED_SPF: ar 0.0.0.0 (vrf 0x60000000, seq 0x80000003) - Router LSA changed 11 Sep 16 11:21:47.024 ospf_iparm_rtrid_cb: rtr ID current = 0.0.0.0 new = 0.0.0.0 18 Sep 16 11:21:47.028 ospf_aux_rtr_thd_vrf_callback_handler: VRF id 0x60000000 and table_id 0xe0000000 instance 0x5a51b09f8bd0 event 0 19 Sep 16 11:21:47.031 ospf_aux_rtr_thd_vrf_result_handler: Rcv VRF Registration result OK for instance 0x5a51b09f8bd0 vrf handle 0xd8000e00 event 0x0 20 Sep 16 11:21:47.031 ospf_aux_rtr_thd_vrf_result_handler: update VRF result handle to valid instance 0x5a51b09f8bd0 122 Sep 16 11:21:56.420 ospf_iparm_rtrid_cb: rtr ID current = 0.0.0.0 new = 3.3.3.3 123 Sep 16 11:21:56.420 ospf_iparm_rtrid_cb: rtr ID current = 3.3.3.3 new = 3.3.3.3 RP/0/RP0/CPU0:R3#show ospf trace all | include db_install Wed Sep 16 11:30:52.360 UTC 12 Sep 16 11:22:05.435 db_install: FC: LSA_CHG: New Rtr LSA: ar 0.0.0.0 lsid 1.1.1.1 advrtr 1.1.1.1 seq 0x80000003 13 Sep 16 11:22:05.435 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 1.1.1.1 advrtr 1.1.1.1 14 Sep 16 11:22:05.435 db_install: FC: LSA_CHG: New Rtr LSA: ar 0.0.0.0 lsid 2.2.2.2 advrtr 2.2.2.2 seq 0x80000003 17 Sep 16 11:22:05.435 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 2.2.2.2 advrtr 2.2.2.2 21 Sep 16 11:22:05.487 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 22 Sep 16 11:22:05.487 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 2.2.2.2 advrtr 2.2.2.2 RP/0/RP0/CPU0:R3#show ospf trace all | include arriv Wed Sep 16 11:31:11.329 UTC RP/0/RP0/CPU0:R3#show ospf trace spf Wed Sep 16 11:31:31.027 UTC Traces for OSPF 1 (Wed Sep 16 11:31:31) Traces returned/requested/available: 175/8192/175 Trace buffer: spf 1 Sep 16 11:21:47.227 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 2 Sep 16 11:21:47.227 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:47.227 ospf_update_done_cb: SPF complete: Elapse time: 1135 ms. 4 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 5 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 6 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 7 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 8 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 9 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 10 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 11 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 12 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 13 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 14 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 15 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 16 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 17 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 18 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 19 Sep 16 11:21:47.227 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 20 Sep 16 11:21:47.323 ospf_force_spf: flag 0 21 Sep 16 11:21:47.520 ospf_force_spf: flag 0 22 Sep 16 11:21:47.618 ospf_run_spf: Begin SPF 23 Sep 16 11:21:47.618 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 24 Sep 16 11:21:47.618 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 25 Sep 16 11:21:47.618 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 26 Sep 16 11:21:47.618 delete_old_routes: area 0.0.0.0 27 Sep 16 11:21:47.618 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 28 Sep 16 11:21:47.618 ospf_run_spf: SPF Dijkstra TRUE 29 Sep 16 11:21:47.619 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 30 Sep 16 11:21:47.619 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:47.619 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 32 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 33 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 34 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 35 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 36 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 37 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 38 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 39 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 40 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 41 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 42 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 43 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 44 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 45 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 46 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 47 Sep 16 11:21:47.619 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 48 Sep 16 11:21:47.619 ospf_run_spf: SPF Processing ends 49 Sep 16 11:21:47.619 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 50 Sep 16 11:21:47.619 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 51 Sep 16 11:21:47.619 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 52 Sep 16 11:21:47.619 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 53 Sep 16 11:21:56.415 ospf_run_spf: Begin SPF 54 Sep 16 11:21:56.415 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 55 Sep 16 11:21:56.415 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 56 Sep 16 11:21:56.415 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 57 Sep 16 11:21:56.416 delete_old_routes: area 0.0.0.0 58 Sep 16 11:21:56.416 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 59 Sep 16 11:21:56.416 ospf_run_spf: SPF Dijkstra TRUE 60 Sep 16 11:21:56.416 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 61 Sep 16 11:21:56.416 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:56.416 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 63 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 64 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 65 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 66 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 67 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 68 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 69 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 70 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 71 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 72 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 73 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 74 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 75 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 76 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 77 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 78 Sep 16 11:21:56.416 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 79 Sep 16 11:21:56.416 ospf_run_spf: SPF Processing ends 80 Sep 16 11:21:56.416 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 81 Sep 16 11:21:56.416 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 82 Sep 16 11:21:56.416 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 83 Sep 16 11:21:56.416 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 84 Sep 16 11:22:01.916 ospf_run_spf: Begin SPF 85 Sep 16 11:22:01.916 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 86 Sep 16 11:22:01.916 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 87 Sep 16 11:22:01.916 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 88 Sep 16 11:22:01.916 delete_old_routes: area 0.0.0.0 89 Sep 16 11:22:01.916 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 90 Sep 16 11:22:01.916 ospf_run_spf: SPF Dijkstra TRUE 91 Sep 16 11:22:01.916 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 92 Sep 16 11:22:01.916 ospf_rib_ipv4_update_done: running rt cnt 0 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 93 Sep 16 11:22:01.916 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 94 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 95 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 96 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 97 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 98 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 99 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 100 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 101 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 102 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 103 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 104 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 105 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 106 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 107 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 108 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 109 Sep 16 11:22:01.916 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 110 Sep 16 11:22:01.916 ospf_run_spf: SPF Processing ends 111 Sep 16 11:22:01.916 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 112 Sep 16 11:22:01.916 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 113 Sep 16 11:22:01.916 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 114 Sep 16 11:22:01.916 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 115 Sep 16 11:22:05.436 ospf_force_spf: flag 0 116 Sep 16 11:22:05.487 ospf_run_spf: Begin SPF 117 Sep 16 11:22:05.487 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 118 Sep 16 11:22:05.487 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 119 Sep 16 11:22:05.487 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 120 Sep 16 11:22:05.487 delete_old_routes: area 0.0.0.0 121 Sep 16 11:22:05.487 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 122 Sep 16 11:22:05.487 ospf_run_spf: SPF Dijkstra TRUE 123 Sep 16 11:22:05.487 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 124 Sep 16 11:22:05.487 ospf_rib_ipv4_update_done: running rt cnt 0 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 125 Sep 16 11:22:05.487 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 126 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 127 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 128 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 129 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 130 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 131 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 132 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 133 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 134 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 135 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 136 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 137 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 138 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 139 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 140 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 141 Sep 16 11:22:05.487 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 142 Sep 16 11:22:05.487 ospf_run_spf: SPF Processing ends 143 Sep 16 11:22:05.487 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 144 Sep 16 11:22:05.487 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 145 Sep 16 11:22:05.887 ospf_run_spf: Begin SPF 146 Sep 16 11:22:05.887 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 147 Sep 16 11:22:05.887 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 148 Sep 16 11:22:05.887 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 149 Sep 16 11:22:05.887 delete_old_routes: area 0.0.0.0 150 Sep 16 11:22:05.887 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 2 lfa cnt 0 (inst 0x60000000) 151 Sep 16 11:22:05.887 ospf_run_spf: SPF Dijkstra TRUE 152 Sep 16 11:22:05.887 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 2 lfa cnt 0 (inst 0x60000000) 153 Sep 16 11:22:05.887 ospf_rib_ipv4_update_done: running rt cnt 3 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 154 Sep 16 11:22:05.887 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 155 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 156 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 157 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 158 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 159 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 160 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 161 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 162 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 163 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats : level 4: batches: 1 routes: 2 164 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats, paths: add 2 err 0, del 0 err 0 165 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 166 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 167 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats : level 6: batches: 1 routes: 1 168 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats, paths: add 1 err 0, del 0 err 0 169 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 170 Sep 16 11:22:05.887 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 171 Sep 16 11:22:05.887 ospf_run_spf: SPF Processing ends 172 Sep 16 11:22:05.887 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 173 Sep 16 11:22:05.887 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 174 Sep 16 11:22:05.887 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 175 Sep 16 11:22:05.887 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000)