RP/0/RP0/CPU0:R2#show ospf trace spf | include Begin SPF Wed Sep 16 11:28:56.260 UTC 22 Sep 16 11:21:41.023 ospf_run_spf: Begin SPF 53 Sep 16 11:21:50.172 ospf_run_spf: Begin SPF 84 Sep 16 11:21:55.934 ospf_run_spf: Begin SPF 116 Sep 16 11:21:59.190 ospf_run_spf: Begin SPF 145 Sep 16 11:21:59.590 ospf_run_spf: Begin SPF 176 Sep 16 11:22:05.482 ospf_run_spf: Begin SPF 205 Sep 16 11:22:05.683 ospf_run_spf: Begin SPF RP/0/RP0/CPU0:R2#show ospf trace adj_cycle Wed Sep 16 11:28:57.322 UTC Traces for OSPF 1 (Wed Sep 16 11:28:57) Traces returned/requested/available: 34/8192/34 Trace buffer: adj_cycle 1 Sep 16 11:21:59.108 ospf_send_dbd: to 1.1.1.1(10.0.12.1) intf Gi0/0/0/0 seq 0x2c6b5d9b 2 Sep 16 11:21:59.123 ospf_rcv_dbd: from 1.1.1.1(10.0.12.1) intf Gi0/0/0/0 seq 0x156cd2c1 3 Sep 16 11:21:59.123 ospf_rcv_dbd: from 1.1.1.1(10.0.12.1) intf Gi0/0/0/0 seq 0x2c6b5d9b 4 Sep 16 11:21:59.123 ospf_send_dbd: to 1.1.1.1(10.0.12.1) intf Gi0/0/0/0 seq 0x2c6b5d9c 5 Sep 16 11:21:59.131 ospf_do_hello_packets: LSA pkt, src 10.0.12.1 intf Gi0/0/0/0 type 3 paksize 126 6 Sep 16 11:21:59.131 ospf_output: LSA pkt, src 10.0.12.2 dest 10.0.12.1 area 0.0.0.0 intf Gi0/0/0/0 7 Sep 16 11:21:59.131 ospf_rcv_dbd: from 1.1.1.1(10.0.12.1) intf Gi0/0/0/0 seq 0x2c6b5d9c 8 Sep 16 11:21:59.131 ospf_output: LSA pkt, src 10.0.12.2 dest 10.0.12.1 area 0.0.0.0 intf Gi0/0/0/0 9 Sep 16 11:21:59.131 ospf_send_req: LS REQ pkt, nbr 1.1.1.1/10.0.12.1 cnt 1 unsent 0 10 Sep 16 11:21:59.139 ospf_do_hello_packets: LSA pkt, src 10.0.12.1 intf Gi0/0/0/0 type 4 paksize 166 11 Sep 16 11:21:59.191 ospf_output: LSA pkt, src 10.0.12.2 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/0 12 Sep 16 11:21:59.192 ospf_do_hello_packets: LSA pkt, src 10.0.12.1 intf Gi0/0/0/0 type 4 paksize 178 13 Sep 16 11:22:01.139 ospf_output: LSA pkt, src 10.0.12.2 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/0 14 Sep 16 11:22:01.161 ospf_do_hello_packets: LSA pkt, src 10.0.12.1 intf Gi0/0/0/0 type 5 paksize 134 15 Sep 16 11:22:05.417 ospf_rcv_dbd: from 3.3.3.3(10.0.23.3) intf Gi0/0/0/1 seq 0x10bc4300 16 Sep 16 11:22:05.417 ospf_send_dbd: to 3.3.3.3(10.0.23.3) intf Gi0/0/0/1 seq 0x27d2308d 17 Sep 16 11:22:05.418 ospf_send_dbd: to 3.3.3.3(10.0.23.3) intf Gi0/0/0/1 seq 0x10bc4300 18 Sep 16 11:22:05.425 ospf_rcv_dbd: from 3.3.3.3(10.0.23.3) intf Gi0/0/0/1 seq 0x10bc4301 19 Sep 16 11:22:05.425 ospf_output: LSA pkt, src 10.0.23.2 dest 10.0.23.3 area 0.0.0.0 intf Gi0/0/0/1 20 Sep 16 11:22:05.425 ospf_send_req: LS REQ pkt, nbr 3.3.3.3/10.0.23.3 cnt 1 unsent 0 21 Sep 16 11:22:05.425 ospf_send_dbd: to 3.3.3.3(10.0.23.3) intf Gi0/0/0/1 seq 0x10bc4301 22 Sep 16 11:22:05.431 ospf_do_hello_packets: LSA pkt, src 10.0.23.3 intf Gi0/0/0/1 type 4 paksize 166 23 Sep 16 11:22:05.431 ospf_do_hello_packets: LSA pkt, src 10.0.23.3 intf Gi0/0/0/1 type 3 paksize 138 24 Sep 16 11:22:05.431 ospf_output: LSA pkt, src 10.0.23.2 dest 10.0.23.3 area 0.0.0.0 intf Gi0/0/0/1 25 Sep 16 11:22:05.433 ospf_output: LSA pkt, src 10.0.12.2 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/0 26 Sep 16 11:22:05.484 ospf_output: LSA pkt, src 10.0.12.2 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/0 27 Sep 16 11:22:05.484 ospf_output: LSA pkt, src 10.0.23.2 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/1 28 Sep 16 11:22:05.492 ospf_do_hello_packets: LSA pkt, src 10.0.23.3 intf Gi0/0/0/1 type 4 paksize 178 29 Sep 16 11:22:05.517 ospf_output: LSA pkt, src 10.0.12.2 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/0 30 Sep 16 11:22:07.431 ospf_output: LSA pkt, src 10.0.23.2 dest 224.0.0.5 area 0.0.0.0 intf Gi0/0/0/1 31 Sep 16 11:22:07.439 ospf_do_hello_packets: LSA pkt, src 10.0.23.3 intf Gi0/0/0/1 type 5 paksize 154 32 Sep 16 11:22:07.441 ospf_do_hello_packets: LSA pkt, src 10.0.12.1 intf Gi0/0/0/0 type 5 paksize 154 33 Sep 16 11:22:10.147 ospf_output: LSA pkt, src 10.0.12.2 dest 10.0.12.1 area 0.0.0.0 intf Gi0/0/0/0 34 Sep 16 11:22:12.157 ospf_do_hello_packets: LSA pkt, src 10.0.12.1 intf Gi0/0/0/0 type 5 paksize 134 RP/0/RP0/CPU0:R2#show ospf trace events | include lsa Wed Sep 16 11:28:58.123 UTC 79 Sep 16 11:21:50.040 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 92 Sep 16 11:21:55.826 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 93 Sep 16 11:21:59.140 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 95 Sep 16 11:22:05.432 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 RP/0/RP0/CPU0:R2#show ospf trace errors Wed Sep 16 11:28:58.934 UTC Traces for OSPF 1 (Wed Sep 16 11:28:59) Traces returned/requested/available: 3/8192/3 Trace buffer: errors 1 Sep 16 11:21:40.526 ospf_nsr_ev_free: Cannot free event 0xf3ac0fc0: flags 0x0,work 0x5d5c7fe28450, handler 0x5d5c59dba02e 2 Sep 16 11:21:40.526 ospf_nsr_ev_free: inst 0x0, nsr_info 0x0, next 0x0 3 Sep 16 11:21:40.623 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:R2#show ospf trace all | include rtr Wed Sep 16 11:28:59.771 UTC 3 Sep 16 11:21:40.736 ospf_build_ex_lsa: no rtrid to build external LSA 6 Sep 16 11:21:50.122 ospf_build_rtr_lsa: area 0.0.0.0 rtrid 2.2.2.2 seq 0x80000001 vrfid 0x60000000 9 Sep 16 11:21:55.884 ospf_build_rtr_lsa: area 0.0.0.0 rtrid 2.2.2.2 seq 0x80000002 vrfid 0x60000000 40 Sep 16 11:21:59.192 ospf_build_rtr_lsa: area 0.0.0.0 rtrid 2.2.2.2 seq 0x80000003 vrfid 0x60000000 70 Sep 16 11:22:05.485 ospf_build_rtr_lsa: area 0.0.0.0 rtrid 2.2.2.2 seq 0x80000004 vrfid 0x60000000 5 Sep 16 11:21:40.733 ospf_apply_routerid: rtrid 2.2.2.2 6 Sep 16 11:21:40.733 ospf_apply_routerid: soft reset, op_rtr_id 0.0.0.0 new 2.2.2.2 48 Sep 16 11:21:40.732 ospf_rtrid_set_configured: new_rtr_id 2.2.2.2 49 Sep 16 11:21:40.732 ospf_rtrid_set_configured: rtrid config by value, op_conf_rtr_id 2.2.2.2 vrfid 60000000 56 Sep 16 11:21:40.739 ospf_rtrid_allocate: checking for old rtrid 0x60000000 57 Sep 16 11:21:40.739 ospf_rtrid_allocate: checking for rtrid config 58 Sep 16 11:21:40.739 ospf_rtrid_allocate: rtrid for vrf 0x60000000 set by numeric config: 2.2.2.2 59 Sep 16 11:21:40.822 ospf_soft_reset_up: rtrid: new 2.2.2.2 old 0.0.0.0 60 Sep 16 11:21:40.822 ospf_soft_reset_up: rtrid changed, check vlink for reconfig 79 Sep 16 11:21:50.039 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 80 Sep 16 11:21:50.224 ospf_if_rtr_id_callback: Router-id sent by IPARM: 2.2.2.2 92 Sep 16 11:21:55.825 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 93 Sep 16 11:21:59.139 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 95 Sep 16 11:22:05.431 ospf_schedule_rtr_lsa: scheduling rtr lsa for area 0.0.0.0 18 Sep 16 11:21:37.034 ospf_nsr_init: Init init-sync continue (rtr thd) timer 132 Sep 16 11:21:38.428 ospf_nsr_init_rtr_thd_timers: Init init-sync continue timer 133 Sep 16 11:21:38.428 ospf_nsr_init_rtr_thd_timers: Init LSA async batching timer 134 Sep 16 11:21:38.428 ospf_nsr_init_rtr_thd_timers: Init LSA sync batching timer 135 Sep 16 11:21:38.428 ospf_nsr_init_rtr_thd_timers: Init LSA batching timer 136 Sep 16 11:21:38.428 ospf_nsr_init_rtr_thd_timers: Init init-sync continue timer 137 Sep 16 11:21:38.428 ospf_nsr_init_rtr_thd_timers: Init LSA async batching timer 138 Sep 16 11:21:38.428 ospf_nsr_init_rtr_thd_timers: Init LSA sync batching timer 139 Sep 16 11:21:38.428 ospf_nsr_init_rtr_thd_timers: Init LSA batching timer 343 Sep 16 11:21:40.525 ospf_aux_rtr_thd_rsi_conn_up_handler: Rcv RSI connection UP in Router thread 346 Sep 16 11:21:40.528 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(6), event(1) 347 Sep 16 11:21:40.528 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(2), event(1) 348 Sep 16 11:21:40.528 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(1), event(1) 421 Sep 16 11:21:40.730 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(5), event(1) 456 Sep 16 11:21:52.026 ospf_nsr_rtr_thd_nsr_milestone_event: Received milestone event from NSR for milestone(4), event(1) 2 Sep 16 11:21:40.526 ospf_rsi_rtr_thd_if_rescan: Added 0 idbs to the router thread batch 34 Sep 16 11:21:40.943 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 0x749cb8000e80 35 Sep 16 11:21:40.943 ospf_aux_rtr_thd_srlg_callback_handler_per_idb: Handling SRLG callback for Gi0/0/0/1 area 0.0.0.0, event 0x0, SRLG handle 0x749cb8000ed0 36 Sep 16 11:21:40.943 ospf_aux_rtr_thd_srlg_result_handler: Rcv SRLG result OK for Gi0/0/0/0, old SRLG handle 0x749cb8000e80, new SRLG handle 0x749cb8000e80 37 Sep 16 11:21:40.943 ospf_aux_rtr_thd_srlg_result_handler: Rcv SRLG result OK for Gi0/0/0/1, old SRLG handle 0x749cb8000ed0, new SRLG handle 0x749cb8000ed0 6 Sep 16 11:21:40.823 ospf_te_set_ospf_rtrid: TE system-id 2.2.2.2 4 Sep 16 11:21:50.122 ospf_build_rtr_lsa: FC: SCHED_SPF: ar 0.0.0.0 (vrf 0x60000000, seq 0x80000001) - Router LSA changed 9 Sep 16 11:21:55.833 ospf_build_rtr_lsa: FC: T_DELAY_RTR_LSA: ar 0.0.0.0, bucket: 11,35 type 2 10 Sep 16 11:21:55.884 ospf_build_rtr_lsa: FC: SCHED_SPF: ar 0.0.0.0 (vrf 0x60000000, seq 0x80000002) - Router LSA changed 13 Sep 16 11:21:59.140 db_install: FC: LSA_CHG: New Rtr LSA: ar 0.0.0.0 lsid 1.1.1.1 advrtr 1.1.1.1 seq 0x80000002 16 Sep 16 11:21:59.140 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 1.1.1.1 advrtr 1.1.1.1 17 Sep 16 11:21:59.142 ospf_build_rtr_lsa: FC: T_DELAY_RTR_LSA: ar 0.0.0.0, bucket: 11,35 type 2 20 Sep 16 11:21:59.192 ospf_build_rtr_lsa: FC: SCHED_SPF: ar 0.0.0.0 (vrf 0x60000000, seq 0x80000003) - Router LSA changed 21 Sep 16 11:21:59.193 db_install: FC: LSA_CHG: Changed Rtr LSA: ar 0.0.0.0 lsid 1.1.1.1 advrtr 1.1.1.1 seq 0x80000003 22 Sep 16 11:21:59.193 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 1.1.1.1 advrtr 1.1.1.1 27 Sep 16 11:22:05.432 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 30 Sep 16 11:22:05.432 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 3.3.3.3 advrtr 3.3.3.3 31 Sep 16 11:22:05.434 ospf_build_rtr_lsa: FC: T_DELAY_RTR_LSA: ar 0.0.0.0, bucket: 11,35 type 2 34 Sep 16 11:22:05.485 ospf_build_rtr_lsa: FC: SCHED_SPF: ar 0.0.0.0 (vrf 0x60000000, seq 0x80000004) - Router LSA changed 35 Sep 16 11:22:05.493 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 36 Sep 16 11:22:05.493 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:40.524 ospf_iparm_rtrid_cb: rtr ID current = 0.0.0.0 new = 0.0.0.0 18 Sep 16 11:21:40.530 ospf_aux_rtr_thd_vrf_callback_handler: VRF id 0x60000000 and table_id 0xe0000000 instance 0x5d5c7fd1a720 event 0 19 Sep 16 11:21:40.533 ospf_aux_rtr_thd_vrf_result_handler: Rcv VRF Registration result OK for instance 0x5d5c7fd1a720 vrf handle 0xb8000e00 event 0x0 20 Sep 16 11:21:40.533 ospf_aux_rtr_thd_vrf_result_handler: update VRF result handle to valid instance 0x5d5c7fd1a720 146 Sep 16 11:21:50.225 ospf_iparm_rtrid_cb: rtr ID current = 0.0.0.0 new = 2.2.2.2 147 Sep 16 11:21:50.225 ospf_iparm_rtrid_cb: rtr ID current = 2.2.2.2 new = 2.2.2.2 RP/0/RP0/CPU0:R2#show ospf trace all | include db_install Wed Sep 16 11:29:19.259 UTC 13 Sep 16 11:21:59.140 db_install: FC: LSA_CHG: New Rtr LSA: ar 0.0.0.0 lsid 1.1.1.1 advrtr 1.1.1.1 seq 0x80000002 16 Sep 16 11:21:59.140 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 1.1.1.1 advrtr 1.1.1.1 21 Sep 16 11:21:59.193 db_install: FC: LSA_CHG: Changed Rtr LSA: ar 0.0.0.0 lsid 1.1.1.1 advrtr 1.1.1.1 seq 0x80000003 22 Sep 16 11:21:59.193 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 1.1.1.1 advrtr 1.1.1.1 27 Sep 16 11:22:05.432 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 30 Sep 16 11:22:05.432 db_install: FC: SCHED_SPF: ar 0.0.0.0 type 1 lsid 3.3.3.3 advrtr 3.3.3.3 35 Sep 16 11:22:05.493 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 36 Sep 16 11:22:05.493 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:R2#show ospf trace all | include arriv Wed Sep 16 11:29:37.534 UTC RP/0/RP0/CPU0:R2#show ospf trace spf Wed Sep 16 11:29:56.439 UTC Traces for OSPF 1 (Wed Sep 16 11:29:57) Traces returned/requested/available: 235/8192/235 Trace buffer: spf 1 Sep 16 11:21:40.724 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 2 Sep 16 11:21:40.724 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:40.724 ospf_update_done_cb: SPF complete: Elapse time: 1038 ms. 4 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 5 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 6 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 7 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 8 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 9 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 10 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 11 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 12 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 13 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 14 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 15 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 16 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 17 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 18 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 19 Sep 16 11:21:40.724 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 20 Sep 16 11:21:40.737 ospf_force_spf: flag 0 21 Sep 16 11:21:40.922 ospf_force_spf: flag 0 22 Sep 16 11:21:41.023 ospf_run_spf: Begin SPF 23 Sep 16 11:21:41.023 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 24 Sep 16 11:21:41.023 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 25 Sep 16 11:21:41.023 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 26 Sep 16 11:21:41.023 delete_old_routes: area 0.0.0.0 27 Sep 16 11:21:41.023 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 28 Sep 16 11:21:41.023 ospf_run_spf: SPF Dijkstra TRUE 29 Sep 16 11:21:41.023 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 30 Sep 16 11:21:41.023 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:41.023 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 32 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 33 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 34 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 35 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 36 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 37 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 38 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 39 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 40 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 41 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 42 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 43 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 44 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 45 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 46 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 47 Sep 16 11:21:41.023 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 48 Sep 16 11:21:41.023 ospf_run_spf: SPF Processing ends 49 Sep 16 11:21:41.023 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 50 Sep 16 11:21:41.023 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 51 Sep 16 11:21:41.023 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 52 Sep 16 11:21:41.023 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 53 Sep 16 11:21:50.172 ospf_run_spf: Begin SPF 54 Sep 16 11:21:50.172 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 55 Sep 16 11:21:50.172 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 56 Sep 16 11:21:50.172 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 57 Sep 16 11:21:50.172 delete_old_routes: area 0.0.0.0 58 Sep 16 11:21:50.172 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 59 Sep 16 11:21:50.172 ospf_run_spf: SPF Dijkstra TRUE 60 Sep 16 11:21:50.172 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 61 Sep 16 11:21:50.172 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:50.172 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 63 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 64 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 65 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 66 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 67 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 68 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 69 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 70 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 71 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 72 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 73 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 74 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 75 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 76 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 77 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 78 Sep 16 11:21:50.172 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 79 Sep 16 11:21:50.172 ospf_run_spf: SPF Processing ends 80 Sep 16 11:21:50.172 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 81 Sep 16 11:21:50.172 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 82 Sep 16 11:21:50.172 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 83 Sep 16 11:21:50.172 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 84 Sep 16 11:21:55.934 ospf_run_spf: Begin SPF 85 Sep 16 11:21:55.934 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 86 Sep 16 11:21:55.934 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 87 Sep 16 11:21:55.934 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 88 Sep 16 11:21:55.934 delete_old_routes: area 0.0.0.0 89 Sep 16 11:21:55.934 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 90 Sep 16 11:21:55.934 ospf_run_spf: SPF Dijkstra TRUE 91 Sep 16 11:21:55.934 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 92 Sep 16 11:21:55.934 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:55.934 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 94 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 95 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 96 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 97 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 98 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 99 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 100 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 101 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 102 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 103 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 104 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 105 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 106 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 107 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 108 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 109 Sep 16 11:21:55.934 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 110 Sep 16 11:21:55.934 ospf_run_spf: SPF Processing ends 111 Sep 16 11:21:55.934 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 112 Sep 16 11:21:55.934 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 113 Sep 16 11:21:55.934 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 114 Sep 16 11:21:55.934 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 115 Sep 16 11:21:59.140 ospf_force_spf: flag 0 116 Sep 16 11:21:59.190 ospf_run_spf: Begin SPF 117 Sep 16 11:21:59.190 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 118 Sep 16 11:21:59.190 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 119 Sep 16 11:21:59.190 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 120 Sep 16 11:21:59.190 delete_old_routes: area 0.0.0.0 121 Sep 16 11:21:59.190 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 122 Sep 16 11:21:59.190 ospf_run_spf: SPF Dijkstra TRUE 123 Sep 16 11:21:59.190 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 124 Sep 16 11:21:59.190 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.190 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 126 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 127 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 128 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 129 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 130 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 131 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 132 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 133 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 134 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats : level 4: batches: 0 routes: 0 135 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 136 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 137 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 138 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats : level 6: batches: 0 routes: 0 139 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 140 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 141 Sep 16 11:21:59.190 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 142 Sep 16 11:21:59.190 ospf_run_spf: SPF Processing ends 143 Sep 16 11:21:59.190 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 144 Sep 16 11:21:59.190 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 145 Sep 16 11:21:59.590 ospf_run_spf: Begin SPF 146 Sep 16 11:21:59.590 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 147 Sep 16 11:21:59.590 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 148 Sep 16 11:21:59.590 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 149 Sep 16 11:21:59.591 delete_old_routes: area 0.0.0.0 150 Sep 16 11:21:59.591 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 1 lfa cnt 0 (inst 0x60000000) 151 Sep 16 11:21:59.591 ospf_run_spf: SPF Dijkstra TRUE 152 Sep 16 11:21:59.591 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 1 lfa cnt 0 (inst 0x60000000) 153 Sep 16 11:21:59.591 ospf_rib_ipv4_update_done: running rt cnt 1 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 154 Sep 16 11:21:59.591 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 155 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 156 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 157 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 158 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 159 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 160 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 161 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 162 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 163 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats : level 4: batches: 1 routes: 1 164 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats, paths: add 1 err 0, del 0 err 0 165 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 166 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 167 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats : level 6: batches: 1 routes: 0 168 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 169 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 170 Sep 16 11:21:59.591 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 171 Sep 16 11:21:59.591 ospf_run_spf: SPF Processing ends 172 Sep 16 11:21:59.591 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 173 Sep 16 11:21:59.591 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 174 Sep 16 11:21:59.591 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 175 Sep 16 11:21:59.591 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 176 Sep 16 11:22:05.482 ospf_run_spf: Begin SPF 177 Sep 16 11:22:05.482 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 178 Sep 16 11:22:05.482 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 179 Sep 16 11:22:05.483 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 180 Sep 16 11:22:05.483 delete_old_routes: area 0.0.0.0 181 Sep 16 11:22:05.483 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 182 Sep 16 11:22:05.483 ospf_run_spf: SPF Dijkstra TRUE 183 Sep 16 11:22:05.483 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 184 Sep 16 11:22:05.483 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.483 ospf_update_done_cb: SPF complete: Elapse time: 0 ms. 186 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 187 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 188 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 189 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 190 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 191 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 192 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 193 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 194 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats : level 4: batches: 1 routes: 1 195 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats, paths: add 1 err 0, del 0 err 0 196 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 197 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 198 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats : level 6: batches: 1 routes: 0 199 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 200 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 201 Sep 16 11:22:05.483 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 202 Sep 16 11:22:05.483 ospf_run_spf: SPF Processing ends 203 Sep 16 11:22:05.483 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 204 Sep 16 11:22:05.483 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 205 Sep 16 11:22:05.683 ospf_run_spf: Begin SPF 206 Sep 16 11:22:05.683 ospf_wdsysmon_olc_start: Begin: Sending Start OLC to wdsysmon, vrf 0x60000000 207 Sep 16 11:22:05.683 ospf_wdsysmon_olc_start: End: Sending Start OLC to wdsysmon, vrf 0x60000000 208 Sep 16 11:22:05.683 spf_intra: area 0.0.0.0 vrf 0x60000000 abr 0 ribupdate 1 209 Sep 16 11:22:05.683 delete_old_routes: area 0.0.0.0 210 Sep 16 11:22:05.683 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 1 lfa cnt 0 (inst 0x60000000) 211 Sep 16 11:22:05.683 ospf_run_spf: SPF Dijkstra TRUE 212 Sep 16 11:22:05.684 ospf_rib_ipv4_update_done: for Q: 6 running rt cnt 1 lfa cnt 0 (inst 0x60000000) 213 Sep 16 11:22:05.684 ospf_rib_ipv4_update_done: running rt cnt 1 (inst 0x60000000) op_rib_conv 0x0 op_rib_conv_event 0x1 214 Sep 16 11:22:05.684 ospf_update_done_cb: SPF complete: Elapse time: 1 ms. 215 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats : level 0: batches: 0 routes: 0 216 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 217 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats : level 1: batches: 0 routes: 0 218 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 219 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats : level 2: batches: 0 routes: 0 220 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 221 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats : level 3: batches: 0 routes: 0 222 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 223 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats : level 4: batches: 2 routes: 2 224 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats, paths: add 2 err 0, del 0 err 0 225 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats : level 5: batches: 0 routes: 0 226 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 227 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats : level 6: batches: 2 routes: 0 228 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 229 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats : level 7: batches: 0 routes: 0 230 Sep 16 11:22:05.684 ospf_update_done_cb: batch stats, paths: add 0 err 0, del 0 err 0 231 Sep 16 11:22:05.684 ospf_run_spf: SPF Processing ends 232 Sep 16 11:22:05.684 ospf_wdsysmon_olc_stop: Begin: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 233 Sep 16 11:22:05.684 ospf_wdsysmon_olc_stop: End: Sending Stop OLC Throttle to wdsysmon, vrf 0x60000000 234 Sep 16 11:22:05.684 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000) 235 Sep 16 11:22:05.684 ospf_rib_ipv4_update_priority: for Q: 5 running rt cnt 0 lfa cnt 0 (inst 0x60000000)