RP/0/RP0/CPU0:PE2#show mpls traffic-eng trace head-end Sun Sep 13 09:09:12.722 UTC 72 wrapping entries (264256 possible, 320 allocated, 0 filtered, 72 total) Sep 13 08:21:33.123 mpls_te/head-end 0/RP0/CPU0 t4470 [PCALC-ECMP] Max BWV Allocated: 20 Sep 13 08:21:33.123 mpls_te/head-end 0/RP0/CPU0 t4470 [PCALC-ECMP] Max BWVE Allocated: 15 Sep 13 08:21:36.416 mpls_te/head-end 0/RP0/CPU0 t4470 NOTFN OwnedResEnd Sep 13 08:21:38.024 mpls_te/head-end 0/RP0/CPU0 t4470 Validating ifindexes for all vifs Sep 13 08:48:55.133 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, Start sync retry timer Sep 13 08:48:55.134 mpls_te/head-end 0/RP0/CPU0 t4470 Batch op IF CREATE with 1 items Sep 13 08:48:55.139 mpls_te/head-end 0/RP0/CPU0 t4470 ifh:0x1c, NOTFN INITIAL; caps ; proto NONE; state not ready Sep 13 08:48:55.139 mpls_te/head-end 0/RP0/CPU0 t4470 ifh:0x1c, NOTFN INITIAL; caps mpls_te; proto NONE; state not ready Sep 13 08:48:55.140 mpls_te/head-end 0/RP0/CPU0 t4470 Batch op CAPS ADD with 1 items Sep 13 08:48:55.140 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, ifh:0x1c, NOTFN STATE, caps ; proto NONE; state down Sep 13 08:48:55.141 mpls_te/head-end 0/RP0/CPU0 t4470 Batch op STATE UPDATE with 1 items Sep 13 08:48:55.142 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, IM op STATE UPDATE for Unknown (ifhndl 0x1c) Sep 13 08:48:55.143 mpls_te/head-end 0/RP0/CPU0 t4470 ifh:0x1c, NOTFN INITIAL; caps mpls; proto mpls; state not ready Sep 13 08:48:55.143 mpls_te/head-end 0/RP0/CPU0 t4470 ifh:0x1c, NOTFN MTU; caps mpls; proto mpls; mtu 1500 Sep 13 08:48:55.143 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, IM op CAPS ADD for Unknown (ifhndl 0x1c) Sep 13 08:48:55.143 mpls_te/head-end 0/RP0/CPU0 t4470 Batch op CAPS ADD with 1 items Sep 13 08:48:55.143 mpls_te/head-end 0/RP0/CPU0 t4470 Batch op MTU UPDATE with 1 items Sep 13 08:48:55.220 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, IM op CAPS ADD for Unknown (ifhndl 0x1c) Sep 13 08:48:55.223 mpls_te/head-end 0/RP0/CPU0 t4470 ifh:0x1c, NOTFN CREATE; caps ipv4; proto ipv4; Sep 13 08:48:55.223 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, IP address is now available Sep 13 08:48:55.223 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, handling vif (0x62510d38) scheduled action ACT_CHECK Sep 13 08:48:55.314 mpls_te/head-end 0/RP0/CPU0 t4470 Unknown (ifhndl 0x1c) : queued IM dest update: 2.2.2.2 Sep 13 08:48:55.314 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, Send IM attribute: old dest = 0.0.0.0, new dest = 2.2.2.2 Sep 13 08:48:55.314 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, Start sync retry timer Sep 13 08:48:55.314 mpls_te/head-end 0/RP0/CPU0 t4470 T:0 Configured classic Affinity: value 0x2 mask 0x2 Sep 13 08:48:55.315 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA Update: Announcing Fwd Adj for D:2.2.2.2 Sep 13 08:48:55.315 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA Update failed (cause 4: tunnel down) Sep 13 08:48:55.315 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, Start sync retry timer Sep 13 08:48:55.315 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, POIndex:10, POType:1, ComputationType:0, Holddown:0 Sep 13 08:48:55.319 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, handling vif (0x62510d38) scheduled action ACT_CHECK Sep 13 08:48:55.319 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, handling vif (0x62510d38) scheduled action ACT_MODIFY_IN_PLACE Sep 13 08:48:55.319 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, handling vif (0x62510d38) scheduled action ACTION_VERIFY_SR Sep 13 08:48:55.420 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, L:2, LL:24014, TE rewrite queueing succeeded Sep 13 08:48:55.420 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, [CURRENT] L:2, ifh:0x1c, TE rewrite returned TRUE Sep 13 08:48:55.420 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, IFH:0x01C, moving state to up Sep 13 08:48:55.420 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA Update on IF state (new state 3: up) Sep 13 08:48:55.420 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA HO timer start, duration 1 msec Sep 13 08:48:55.421 mpls_te/head-end 0/RP0/CPU0 t4470 Batch op STATE UPDATE with 1 items Sep 13 08:48:55.421 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, IM op STATE UPDATE for Unknown (ifhndl 0x1c) Sep 13 08:48:55.423 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA HO timer expired Sep 13 08:48:55.423 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA Update: Announcing Fwd Adj for D:2.2.2.2 Sep 13 08:48:55.423 mpls_te/head-end 0/RP0/CPU0 t4470 Created autoroute entry 2.2.2.2, area 2 0; total AA entries 1 Sep 13 08:48:55.423 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA Announced: yes Sep 13 08:48:55.423 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, added AA, D:2.2.2.2, IGP: 2, area 0 Sep 13 08:48:55.423 mpls_te/head-end 0/RP0/CPU0 t4470 Sent autoroute-announce tunnel list for 2.2.2.2 to IGP: OSPF, area 0: 1 tunnels Sep 13 08:48:56.134 mpls_te/head-end 0/RP0/CPU0 t4470 Validating ifindexes for all vifs Sep 13 08:48:56.134 mpls_te/head-end 0/RP0/CPU0 t4470 Bulk ifindex lookup for 1 vifs Sep 13 08:48:56.136 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, ifindex set to 9 Sep 13 08:49:10.316 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, Sync retry timer expired Sep 13 08:59:51.240 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, L:2, LL:24014, TE rewrite delete queueing succeeded Sep 13 08:59:51.240 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, IFH:0x01C, moving state to down Sep 13 08:59:51.240 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA Update on IF state (new state 6: down immediate) Sep 13 08:59:51.240 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA Update: Removing Fwd Adj for D:2.2.2.2 Sep 13 08:59:51.240 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA Announced: no Sep 13 08:59:51.240 mpls_te/head-end 0/RP0/CPU0 t4470 Removed tunnel T:0, D:2.2.2.2 from autoroute list for IGP: OSPF, area 0 Sep 13 08:59:51.240 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA Update: Removing Fwd Adj for D:2.2.2.2 Sep 13 08:59:51.240 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, FA Update failed (cause 7: was not announced) Sep 13 08:59:51.240 mpls_te/head-end 0/RP0/CPU0 t4470 T:0 Deleted classic Affinity: value 0x2 mask 0x2 Sep 13 08:59:51.240 mpls_te/head-end 0/RP0/CPU0 t4470 Unknown (ifhndl 0x1c) : queued IM dest update: 0.0.0.0 Sep 13 08:59:51.242 mpls_te/head-end 0/RP0/CPU0 t4470 Batch op STATE UPDATE with 1 items Sep 13 08:59:51.243 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, IM op STATE UPDATE for Unknown (ifhndl 0x1c) Sep 13 08:59:51.243 mpls_te/head-end 0/RP0/CPU0 t4470 Sent autoroute-announce tunnel list for 2.2.2.2 to IGP: OSPF, area 0: 0 tunnels Sep 13 08:59:51.243 mpls_te/head-end 0/RP0/CPU0 t4470 Deleted autoroute entry 2.2.2.2, area 2 0; total AA entries 0 Sep 13 08:59:51.244 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, handling vif (0x62510d38) scheduled action ACT_CHECK Sep 13 08:59:51.244 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, handling vif (0x62510d38) scheduled action ACTION_VERIFY_SR Sep 13 08:59:51.244 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, handling vif (0x62510d38) scheduled action ACT_MODIFY_IN_PLACE Sep 13 08:59:51.323 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, IM interface delete operation queued Sep 13 08:59:51.323 mpls_te/head-end 0/RP0/CPU0 t4470 Batch op IF DELETE with 1 items Sep 13 08:59:51.417 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, IM op IF DELETE for Unknown (ifhndl 0x1c) Sep 13 08:59:51.417 mpls_te/head-end 0/RP0/CPU0 t4470 T:0, Type:TE, deleted; new tunnel count = 0 Sep 13 08:59:51.418 mpls_te/head-end 0/RP0/CPU0 t4470 ifh:0x1c, NOTFN DELETE; caps ipv4; proto ipv4; Sep 13 08:59:51.418 mpls_te/head-end 0/RP0/CPU0 t4470 Unable to find tunnel with handle: ifh 0x1c RP/0/RP0/CPU0:PE2#show mpls traffic-eng trace link Sun Sep 13 09:09:13.434 UTC 292 wrapping entries (67648 possible, 576 allocated, 0 filtered, 292 total) Sep 13 08:21:33.123 mpls_te/link 0/RP0/CPU0 t4470 DS-TE mode change: prev - 0, new - 1 Sep 13 08:21:33.123 mpls_te/link 0/RP0/CPU0 t4470 TE process restarting; aborting DS-TE mode change Sep 13 08:21:33.735 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:381: lm_iarm_control_cb_fn: connection to IARM established. Sep 13 08:21:36.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry Sep 13 08:21:36.717 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/0 (ifh 0x8) into db based on name and handle Sep 13 08:21:36.717 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x8), flood 0, force 0, Nbr cnt 0 Sep 13 08:21:36.717 mpls_te/link 0/RP0/CPU0 t4470 RSI: Registering interface GigabitEthernet0/0/0/0 (ifhndl 0x8) (0x8) for SRLG Notification Sep 13 08:21:36.717 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/0 (ifh 0x8) Created [1 links total], enable 0 Sep 13 08:21:36.719 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) BW attribute 0 kbps Sep 13 08:21:36.719 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) physical_bw 125000000 Bps Sep 13 08:21:36.722 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:21:36.722 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 13 08:21:36.722 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/0 (ifh 0x8), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.722 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/0 (ifh 0x8), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.722 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/0 (ifh 0x8), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.722 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/0 (ifh 0x8), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.722 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/0 (ifh 0x8) state down Sep 13 08:21:36.726 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/0 (ifh 0x8) to IARM batch Sep 13 08:21:36.726 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:756: lm_iarm_reg_ipv4_add: sent pulse (0) to flush IARM IPv4 registration batch. Sep 13 08:21:36.726 mpls_te/link 0/RP0/CPU0 t4470 Configured link (0x8) with classic affinity: value 0x1 Sep 13 08:21:36.726 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry Sep 13 08:21:36.726 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/1 (ifh 0x10) into db based on name and handle Sep 13 08:21:36.726 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x10), flood 0, force 0, Nbr cnt 0 Sep 13 08:21:36.726 mpls_te/link 0/RP0/CPU0 t4470 RSI: Registering interface GigabitEthernet0/0/0/1 (ifhndl 0x10) (0x10) for SRLG Notification Sep 13 08:21:36.726 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/1 (ifh 0x10) Created [2 links total], enable 0 Sep 13 08:21:36.727 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) BW attribute 0 kbps Sep 13 08:21:36.727 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) physical_bw 125000000 Bps Sep 13 08:21:36.730 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) max_bw 100000 (Kbps), pool0_bw 100000 (Kbps) Sep 13 08:21:36.730 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 13 08:21:36.730 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x10), max_res_bw 100000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.730 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x10), Pool0* max_bw 100000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.730 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x10), max_res_bw 100000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.730 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x10), Pool0* max_bw 100000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.730 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/1 (ifh 0x10) state down Sep 13 08:21:36.733 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/1 (ifh 0x10) to IARM batch Sep 13 08:21:36.733 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry Sep 13 08:21:36.733 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/2 (ifh 0x18) into db based on name and handle Sep 13 08:21:36.733 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x18), flood 0, force 0, Nbr cnt 0 Sep 13 08:21:36.733 mpls_te/link 0/RP0/CPU0 t4470 RSI: Registering interface GigabitEthernet0/0/0/2 (ifhndl 0x18) (0x18) for SRLG Notification Sep 13 08:21:36.733 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/2 (ifh 0x18) Created [3 links total], enable 0 Sep 13 08:21:36.734 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) BW attribute 0 kbps Sep 13 08:21:36.734 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) physical_bw 125000000 Bps Sep 13 08:21:36.813 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:21:36.813 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 13 08:21:36.813 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x18), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.813 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x18), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.813 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x18), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.813 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x18), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:21:36.813 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/2 (ifh 0x18) state down Sep 13 08:21:36.816 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/2 (ifh 0x18) to IARM batch Sep 13 08:21:36.816 mpls_te/link 0/RP0/CPU0 t4470 Configured link (0x18) with classic affinity: value 0x2 Sep 13 08:21:36.816 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 17, proto 0, opcode 35 (CREATE/DEL) Sep 13 08:21:36.816 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), intf_type 15, parent i/f None (ifh 0x0), caps 0 Sep 13 08:21:36.816 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/0 (ifh 0x8), state 17, proto 0, opcode 35 Sep 13 08:21:36.817 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/0 (ifh 0x8), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:21:36.817 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) BW attribute 0 kbps Sep 13 08:21:36.817 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) physical_bw 125000000 Bps Sep 13 08:21:36.817 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:21:36.817 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 13 08:21:36.817 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:21:36.817 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 13 08:21:36.817 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x8) suppressed, system not ready Sep 13 08:21:36.817 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), state 17, proto 0, opcode 35 (CREATE/DEL) Sep 13 08:21:36.817 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), intf_type 15, parent i/f None (ifh 0x0), caps 0 Sep 13 08:21:36.817 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/1 (ifh 0x10), state 17, proto 0, opcode 35 Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/1 (ifh 0x10), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) BW attribute 0 kbps Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) physical_bw 125000000 Bps Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) max_bw 100000 (Kbps), pool0_bw 100000 (Kbps) Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x10) suppressed, system not ready Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), state 17, proto 0, opcode 35 (CREATE/DEL) Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), intf_type 15, parent i/f None (ifh 0x0), caps 0 Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/2 (ifh 0x18), state 17, proto 0, opcode 35 Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/2 (ifh 0x18), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) BW attribute 0 kbps Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) physical_bw 125000000 Bps Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 13 08:21:36.818 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x18) suppressed, system not ready Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 RSI: Synchronous batch handler: 3 items Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 RSI: Event Update with 0 SRLG values for GigabitEthernet0/0/0/0 (ifhndl 0x8) (0x8) [change: NO, old count 0] Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 RSI: Event Update with 0 SRLG values for GigabitEthernet0/0/0/1 (ifhndl 0x10) (0x10) [change: NO, old count 0] Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 RSI: Event Update with 1 SRLG values for GigabitEthernet0/0/0/2 (ifhndl 0x18) (0x18) [change: YES, old count 0] Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 RSI: Deleting all 0 SRLG values for GigabitEthernet0/0/0/2 (ifhndl 0x18) (0x18) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 RSI: Adding all 1 SRLG values to GigabitEthernet0/0/0/2 (ifhndl 0x18) (0x18) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 RSI: Added SRLG value 100 to GigabitEthernet0/0/0/2 (ifhndl 0x18) (0x18) => [Total SRLGs for link: 1] Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 RSI: Checkpointing 1 SRLG values for GigabitEthernet0/0/0/2 (ifhndl 0x18) (0x18) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5327: Periodic Flooding request canceled: flooding disabled Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 RSI: Sent registration for 3 interfaces (Success 3, failure 0) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/0 (ifh 0x8),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_process_link_capacity(): capacity not changed link GigabitEthernet0/0/0/0 (ifh 0x8), current bw 125000000 (Kbps), new bw 125000000 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/1 (ifh 0x10),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_process_link_capacity(): capacity not changed link GigabitEthernet0/0/0/1 (ifh 0x10), current bw 125000000 (Kbps), new bw 125000000 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) max_bw 100000 (Kbps), pool0_bw 100000 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/2 (ifh 0x18),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_process_link_capacity(): capacity not changed link GigabitEthernet0/0/0/2 (ifh 0x18), current bw 125000000 (Kbps), new bw 125000000 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/3 (ifh 0x20),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_im_attr_capacity_handler: link MgmtEth0/RP0/CPU0/0 (ifh 0x28),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 13 08:21:36.819 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:279: lm_iarm_handle_pulse: received pulse (0), flushing IARM IPv4 registration batch. Sep 13 08:21:36.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:623: lm_iarm_flush: sent 3 IPv4 (un)register requests to IARM. Sep 13 08:21:37.312 mpls_te/link 0/RP0/CPU0 t4470 SRLG-producer connected Sep 13 08:21:37.315 mpls_te/link 0/RP0/CPU0 t4470 RSI SRLG-producer registration done successfully Sep 13 08:21:37.315 mpls_te/link 0/RP0/CPU0 t4470 Replaying learned SRLGs on all links to RSI Sep 13 08:21:37.315 mpls_te/link 0/RP0/CPU0 t4470 Replaying learned SRLGs on all termination interfaces to RSI Sep 13 08:21:37.719 mpls_te/link 0/RP0/CPU0 t4470 Validating ifindexes for all links Sep 13 08:21:37.719 mpls_te/link 0/RP0/CPU0 t4470 Bulk ifindex lookup for 3 links Sep 13 08:21:37.721 mpls_te/link 0/RP0/CPU0 t4470 Link GigabitEthernet0/0/0/0 (ifhndl 0x8) (0x8) ifindex set to 3 Sep 13 08:21:37.721 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x8), flood 0, force 1, Nbr cnt 0 Sep 13 08:21:37.721 mpls_te/link 0/RP0/CPU0 t4470 Link GigabitEthernet0/0/0/1 (ifhndl 0x10) (0x10) ifindex set to 4 Sep 13 08:21:37.721 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x10), flood 0, force 1, Nbr cnt 0 Sep 13 08:21:37.721 mpls_te/link 0/RP0/CPU0 t4470 Link GigabitEthernet0/0/0/2 (ifhndl 0x18) (0x18) ifindex set to 5 Sep 13 08:21:37.721 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x18), flood 0, force 1, Nbr cnt 0 Sep 13 08:21:39.222 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:119: Unable to process adj-change from IGP OSPFarea 0 on link Loopback0 (ifh 0x14) with handle 0x14, reverting to name Sep 13 08:21:39.222 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:145: Unable to process adj-change from IGP OSPF area 0 on link Loopback0 (ifh 0x14) (0 nbrs): link not found Sep 13 08:21:41.624 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2611: Handling area change: IGP OSPF area 0, is_up = 1, router-id 9.9.9.9 Sep 13 08:21:41.624 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x8), flood 0, force 0, Nbr cnt 0 Sep 13 08:21:41.624 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x10), flood 0, force 0, Nbr cnt 0 Sep 13 08:21:41.624 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x18), flood 0, force 0, Nbr cnt 0 Sep 13 08:21:41.624 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5406: Periodic Flooding for igp-type: 2 area: 0 0 seconds Sep 13 08:21:41.712 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5214: System change: flooding to all links/areas: reason area state change Sep 13 08:21:45.529 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 13 08:21:45.529 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), intf_type 15, parent i/f None (ifh 0x0), caps 26 Sep 13 08:21:45.529 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/0 (ifh 0x8), state 17, proto 12, opcode 35 Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/0 (ifh 0x8), add True Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/0 (ifh 0x8) to IARM batch Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:756: lm_iarm_reg_ipv4_add: sent pulse (0) to flush IARM IPv4 registration batch. Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/0 (ifh 0x8), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) BW attribute 0 kbps Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) physical_bw 125000000 Bps Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x8) suppressed, no change Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), intf_type 15, parent i/f None (ifh 0x0), caps 26 Sep 13 08:21:45.531 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/1 (ifh 0x10), state 17, proto 12, opcode 35 Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/1 (ifh 0x10), add True Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/1 (ifh 0x10) to IARM batch Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/1 (ifh 0x10), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) BW attribute 0 kbps Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) physical_bw 125000000 Bps Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) max_bw 100000 (Kbps), pool0_bw 100000 (Kbps) Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x10) suppressed, no change Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), intf_type 15, parent i/f None (ifh 0x0), caps 26 Sep 13 08:21:45.532 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/2 (ifh 0x18), state 17, proto 12, opcode 35 Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/2 (ifh 0x18), add True Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/2 (ifh 0x18) to IARM batch Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/2 (ifh 0x18), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) BW attribute 0 kbps Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) physical_bw 125000000 Bps Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x18) suppressed, no change Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x8), state 1, proto 12, opcode 37 Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x10), state 1, proto 12, opcode 37 Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:21:45.533 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x18), state 1, proto 12, opcode 37 Sep 13 08:21:45.612 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:279: lm_iarm_handle_pulse: received pulse (0), flushing IARM IPv4 registration batch. Sep 13 08:21:45.612 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:623: lm_iarm_flush: sent 3 IPv4 (un)register requests to IARM. Sep 13 08:21:45.636 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/0 (ifh 0x8), ct=0x0, ps=0x0, addr 10.4.9.9 Sep 13 08:21:45.636 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/1 (ifh 0x10), ct=0x0, ps=0x0, addr 10.6.9.9 Sep 13 08:21:45.636 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/2 (ifh 0x18), ct=0x0, ps=0x0, addr 10.8.9.9 Sep 13 08:21:45.932 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:21:45.932 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x8), state 2, proto 12, opcode 37 Sep 13 08:21:45.932 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:21:45.932 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x10), state 2, proto 12, opcode 37 Sep 13 08:21:45.932 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:21:45.932 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x18), state 2, proto 12, opcode 37 Sep 13 08:21:46.417 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:21:46.417 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x8), state 3, proto 12, opcode 37 Sep 13 08:21:46.417 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x8), flood 0, force 0, Nbr cnt 0 Sep 13 08:21:46.417 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/0 (ifh 0x8), area 0, IGP OSPF Sep 13 08:21:46.517 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x8) (0 nbrs) in area 0, IGP OSPF Sep 13 08:21:46.517 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/0 (ifh 0x8), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:21:46.517 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x8) new igp admin weight 30, prev. isis wt 0, ospf wt 0 Sep 13 08:21:46.517 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:935: Link GigabitEthernet0/0/0/0 (ifh 0x8): Added area 0 IGP OSPF Sep 13 08:21:46.518 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1019: Link GigabitEthernet0/0/0/0 (ifh 0x8): Update nbrs for area 0 IGP OSPF Sep 13 08:21:46.518 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/0 (ifh 0x8), area 0, protocol OSPF, weight 30 Sep 13 08:21:46.518 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2523: link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0: no neighbor changes Sep 13 08:21:46.518 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x8) data changed Sep 13 08:21:46.518 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:21:46.518 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x10), state 3, proto 12, opcode 37 Sep 13 08:21:46.518 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x10), flood 0, force 0, Nbr cnt 0 Sep 13 08:21:46.518 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/1 (ifh 0x10), area 0, IGP OSPF Sep 13 08:21:46.615 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:21:46.615 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x18), state 3, proto 12, opcode 37 Sep 13 08:21:46.615 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x18), flood 0, force 0, Nbr cnt 0 Sep 13 08:21:46.615 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/2 (ifh 0x18), area 0, IGP OSPF Sep 13 08:21:46.617 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x10) (0 nbrs) in area 0, IGP OSPF Sep 13 08:21:46.617 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x10), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:21:46.617 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x10) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 13 08:21:46.617 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:935: Link GigabitEthernet0/0/0/1 (ifh 0x10): Added area 0 IGP OSPF Sep 13 08:21:46.617 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1019: Link GigabitEthernet0/0/0/1 (ifh 0x10): Update nbrs for area 0 IGP OSPF Sep 13 08:21:46.617 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/1 (ifh 0x10), area 0, protocol OSPF, weight 10 Sep 13 08:21:46.618 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2523: link GigabitEthernet0/0/0/1 (ifh 0x10), IGP OSPF area 0: no neighbor changes Sep 13 08:21:46.618 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x10) data changed Sep 13 08:21:46.618 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x8) (0 nbrs) in area 0, IGP OSPF Sep 13 08:21:46.618 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/0 (ifh 0x8), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:21:46.618 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x8) new igp admin weight 30, prev. isis wt 0, ospf wt 0 Sep 13 08:21:46.618 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2523: link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0: no neighbor changes Sep 13 08:21:46.618 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x10) (0 nbrs) in area 0, IGP OSPF Sep 13 08:21:46.618 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x10), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:21:46.618 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x10) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 13 08:21:46.618 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2523: link GigabitEthernet0/0/0/1 (ifh 0x10), IGP OSPF area 0: no neighbor changes Sep 13 08:21:46.636 mpls_te/link 0/RP0/CPU0 t4470 Validating ifindexes for all links Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/2 (ifh 0x18), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x18) new igp admin weight 20, prev. isis wt 0, ospf wt 0 Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:935: Link GigabitEthernet0/0/0/2 (ifh 0x18): Added area 0 IGP OSPF Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1019: Link GigabitEthernet0/0/0/2 (ifh 0x18): Update nbrs for area 0 IGP OSPF Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/2 (ifh 0x18), area 0, protocol OSPF, weight 20 Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2523: link GigabitEthernet0/0/0/2 (ifh 0x18), IGP OSPF area 0: no neighbor changes Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x18) data changed Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/2 (ifh 0x18), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x18) new igp admin weight 20, prev. isis wt 0, ospf wt 0 Sep 13 08:21:46.716 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2523: link GigabitEthernet0/0/0/2 (ifh 0x18), IGP OSPF area 0: no neighbor changes Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x8) (0 nbrs) in area 0, IGP OSPF Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/0 (ifh 0x8), tot_nbrs 0, subnet type 1, flags 0xb Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1019: Link GigabitEthernet0/0/0/0 (ifh 0x8): Update nbrs for area 0 IGP OSPF Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/0 (ifh 0x8), area 0, protocol 2, subnet type 1 Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x8) new igp admin weight 30, prev. isis wt 0, ospf wt 0 Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2498: Link GigabitEthernet0/0/0/0 (ifh 0x8): update DB for 1 neighbors Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1559: Received neighbor 10.4.9.4 on link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0 Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1578: Rcvd nbr node ID 4.4.4.4, state 1 Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1596: Matching existing neighbor found? False Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1714: Neighbor unknown: creating new one Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1286: link GigabitEthernet0/0/0/0 (ifh 0x8): setting IGP cost: 30, in area 0, protocol OSPF Sep 13 08:21:47.133 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1429: link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0: subnet type changed to 1 Sep 13 08:21:47.134 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/0 (ifh 0x8) nbr addr 10.4.9.4, count 1, nbr state 1 Sep 13 08:21:47.134 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x8) data changed Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/2 (ifh 0x18), tot_nbrs 0, subnet type 1, flags 0xb Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1019: Link GigabitEthernet0/0/0/2 (ifh 0x18): Update nbrs for area 0 IGP OSPF Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/2 (ifh 0x18), area 0, protocol 2, subnet type 1 Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x18) new igp admin weight 20, prev. isis wt 0, ospf wt 0 Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2498: Link GigabitEthernet0/0/0/2 (ifh 0x18): update DB for 1 neighbors Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1559: Received neighbor 10.8.9.8 on link GigabitEthernet0/0/0/2 (ifh 0x18), IGP OSPF area 0 Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1578: Rcvd nbr node ID 8.8.8.8, state 1 Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1596: Matching existing neighbor found? False Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1714: Neighbor unknown: creating new one Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1286: link GigabitEthernet0/0/0/2 (ifh 0x18): setting IGP cost: 20, in area 0, protocol OSPF Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1429: link GigabitEthernet0/0/0/2 (ifh 0x18), IGP OSPF area 0: subnet type changed to 1 Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/2 (ifh 0x18) nbr addr 10.8.9.8, count 2, nbr state 1 Sep 13 08:21:48.672 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x18) data changed Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x10) (0 nbrs) in area 0, IGP OSPF Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x10), tot_nbrs 0, subnet type 1, flags 0xb Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1019: Link GigabitEthernet0/0/0/1 (ifh 0x10): Update nbrs for area 0 IGP OSPF Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/1 (ifh 0x10), area 0, protocol 2, subnet type 1 Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x10) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:2498: Link GigabitEthernet0/0/0/1 (ifh 0x10): update DB for 1 neighbors Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1559: Received neighbor 10.6.9.6 on link GigabitEthernet0/0/0/1 (ifh 0x10), IGP OSPF area 0 Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1578: Rcvd nbr node ID 6.6.6.6, state 1 Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1596: Matching existing neighbor found? False Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1714: Neighbor unknown: creating new one Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1286: link GigabitEthernet0/0/0/1 (ifh 0x10): setting IGP cost: 10, in area 0, protocol OSPF Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1429: link GigabitEthernet0/0/0/1 (ifh 0x10), IGP OSPF area 0: subnet type changed to 1 Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/1 (ifh 0x10) nbr addr 10.6.9.6, count 3, nbr state 1 Sep 13 08:21:48.820 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x10) data changed Sep 13 08:24:36.927 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:5471: Forced Flooding for IGP OSPF, area 0 Sep 13 08:48:55.143 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK: rrr_lm_im_attr_capacity_handler: link Unknown (ifh 0x1c),bw 0 (kbps), bw2 0 (Bps), event 0 Sep 13 08:48:55.223 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3722: lm_im_handler: link Unknown (ifh 0x1c), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 13 08:48:55.223 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3727: lm_im_handler: link Unknown (ifh 0x1c), intf_type 36, parent i/f None (ifh 0x0), caps 26 Sep 13 08:48:55.223 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3808: lm_im_handler: IM_CREATE: link Unknown (ifh 0x1c), state 17, proto 12, opcode 35 Sep 13 08:59:51.418 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3722: lm_im_handler: link Unknown (ifh 0x1c), state 17, proto 12, opcode 36 (CREATE/DEL) Sep 13 08:59:51.418 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3727: lm_im_handler: link Unknown (ifh 0x1c), intf_type 36, parent i/f None (ifh 0x0), caps 26 Sep 13 08:59:51.418 mpls_te/link 0/RP0/CPU0 t4470 LM_LINK:3939: lm_im_handler: IM_DEL: link Unknown (ifh 0x1c), state 17, proto 12, opcode 36 RP/0/RP0/CPU0:PE2#show rsvp trace signalling Sun Sep 13 09:09:13.724 UTC 23 wrapping entries (264256 possible, 64 allocated, 0 filtered, 23 total) Sep 13 08:29:50.195 rsvp/sig 0/RP0/CPU0 t4314 SIG:4549: PATH creating network: GigabitEthernet0/0/0/2 (ifh 0x18), obj len: 216, IP src: 2.2.2.2 IP dst: 9.9.9.9, psbs: 0, rsbs: 0 Sep 13 08:29:50.195 rsvp/sig 0/RP0/CPU0 t4314 SIG:721: PATH Tunnel IPv4 created: dst (9.9.9.9:0), src (2.2.2.2:2) Sep 13 08:29:50.214 rsvp/sig 0/RP0/CPU0 t4314 SIG:4649: RESV creating tail: local/nbor (9.9.9.9/8.8.8.8), obj len: 80, None (ifh 0x0), psbs: 1, rsbs: 0 Sep 13 08:29:50.214 rsvp/sig 0/RP0/CPU0 t4314 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000005, psb flags: 0xc0000008, local/nbor 9.9.9.9/8.8.8.8, obj len: 0x50, wedged 0 Sep 13 08:29:50.214 rsvp/sig 0/RP0/CPU0 t4314 SIG:528: RESV Tunnel IPv4 outgoing: dst (9.9.9.9:0), src (2.2.2.2:2) Sep 13 08:29:50.214 rsvp/sig 0/RP0/CPU0 t4314 SIG:362: RESV Tunnel IPv4 created: dst (9.9.9.9:0), src (2.2.2.2:2) Sep 13 08:30:29.624 rsvp/sig 0/RP0/CPU0 t4314 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000004, psb flags: 0xc0000018, local/nbor 9.9.9.9/8.8.8.8, obj len: 0x50, wedged 0 Sep 13 08:30:29.624 rsvp/sig 0/RP0/CPU0 t4314 SIG:528: RESV Tunnel IPv4 outgoing: dst (9.9.9.9:0), src (2.2.2.2:2) Sep 13 08:30:29.624 rsvp/sig 0/RP0/CPU0 t4314 SIG:804: PATH Tunnel IPv4 changed: dst (9.9.9.9:0), src (2.2.2.2:2) Sep 13 08:48:55.323 rsvp/sig 0/RP0/CPU0 t4314 SIG:4545: PATH creating head: local/nbor/nhop (9.9.9.9/8.8.8.8/10.8.9.8), GigabitEthernet0/0/0/2 (ifh 0x18), psbs: 0, rsbs: 0 Sep 13 08:48:55.323 rsvp/sig 0/RP0/CPU0 t4314 SIG:2836: PATH outgoing creating: flags:0xc0000005, local_rid:9.9.9.9, nbor:8.8.8.8, nhop:10.8.9.8, next_ifh:GigabitEthernet0/0/0/2 (ifh 0x18), obj_len: 0 Sep 13 08:48:55.323 rsvp/sig 0/RP0/CPU0 t4314 SIG:887: PATH Tunnel IPv4 outgoing: dst (2.2.2.2:0), src (9.9.9.9:2) Sep 13 08:48:55.323 rsvp/sig 0/RP0/CPU0 t4314 SIG:721: PATH Tunnel IPv4 created: dst (2.2.2.2:0), src (9.9.9.9:2) Sep 13 08:48:55.413 rsvp/sig 0/RP0/CPU0 t4314 SIG:4654: RESV creating network: GigabitEthernet0/0/0/2 (ifh 0x18), in GigabitEthernet0/0/0/2 (ifh 0x18), obj len: 140, IP src: 10.8.9.8, psbs: 1, rsbs: 0 Sep 13 08:48:55.413 rsvp/sig 0/RP0/CPU0 t4314 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/2 (ifh 0x18), current (0 Bps), port 0, src 9.9.9.9 id 2 Sep 13 08:48:55.413 rsvp/sig 0/RP0/CPU0 t4314 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000011, psb flags: 0xc0000004, local/nbor 9.9.9.9/0.0.0.0, obj len: 0x8c, wedged 0 Sep 13 08:48:55.413 rsvp/sig 0/RP0/CPU0 t4314 SIG:362: RESV Tunnel IPv4 created: dst (2.2.2.2:0), src (9.9.9.9:2) Sep 13 08:48:55.422 rsvp/sig 0/RP0/CPU0 t4314 SIG:492: Assigned backup None (ifh 0x0): dst (2.2.2.2:0), src (9.9.9.9:2) Sep 13 08:59:51.247 rsvp/sig 0/RP0/CPU0 t4314 SIG:3929: PATH destroy: dst (2.2.2.2:0), src (9.9.9.9:2) reason: (5): State deleted due to client app Sep 13 08:59:51.247 rsvp/sig 0/RP0/CPU0 t4314 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000004, pfc flags 0x80180009 Sep 13 08:59:51.247 rsvp/sig 0/RP0/CPU0 t4314 SIG:1214: RESV destroy: dst (2.2.2.2:0), src (9.9.9.9:2) reason: (5): State deleted due to client app Sep 13 08:59:51.247 rsvp/sig 0/RP0/CPU0 t4314 SIG:1216: RESV destroy: flags 0x180000, rsb flags 0xc0000030, request flags 0x0 Sep 13 08:59:51.247 rsvp/sig 0/RP0/CPU0 t4314 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/2 (ifh 0x18), current (0 Bps), port 0, src 9.9.9.9 id 2