RP/0/RP0/CPU0:PE1#show mpls traffic-eng trace head-end
Sun Sep 13 12:41:17.649 UTC
58 wrapping entries (264256 possible, 320 allocated, 0 filtered, 58 total)
Sep 13 12:12:41.304 mpls_te/head-end 0/RP0/CPU0 t4320  [PCALC-ECMP] Max BWV Allocated: 20
Sep 13 12:12:41.304 mpls_te/head-end 0/RP0/CPU0 t4320  [PCALC-ECMP] Max BWVE Allocated: 15
Sep 13 12:12:44.910 mpls_te/head-end 0/RP0/CPU0 t4320  NOTFN OwnedResEnd
Sep 13 12:12:46.422 mpls_te/head-end 0/RP0/CPU0 t4320  Validating ifindexes for all vifs
Sep 13 12:26:24.903 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, Start sync retry timer
Sep 13 12:26:24.903 mpls_te/head-end 0/RP0/CPU0 t4320  Batch op IF CREATE with 1 items
Sep 13 12:26:24.908 mpls_te/head-end 0/RP0/CPU0 t4320  ifh:0x1c, NOTFN INITIAL; caps ; proto NONE; state not ready
Sep 13 12:26:24.908 mpls_te/head-end 0/RP0/CPU0 t4320  ifh:0x1c, NOTFN INITIAL; caps mpls_te; proto NONE; state not ready
Sep 13 12:26:24.910 mpls_te/head-end 0/RP0/CPU0 t4320  Batch op CAPS ADD with 1 items
Sep 13 12:26:24.910 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, ifh:0x1c, NOTFN STATE, caps ; proto NONE; state down
Sep 13 12:26:24.911 mpls_te/head-end 0/RP0/CPU0 t4320  Batch op STATE UPDATE with 1 items
Sep 13 12:26:24.913 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IM op STATE UPDATE for tunnel-te0 (ifhndl 0x1c) 
Sep 13 12:26:24.913 mpls_te/head-end 0/RP0/CPU0 t4320  ifh:0x1c, NOTFN INITIAL; caps mpls; proto mpls; state not ready
Sep 13 12:26:24.913 mpls_te/head-end 0/RP0/CPU0 t4320  ifh:0x1c, NOTFN MTU; caps mpls; proto mpls; mtu 1500
Sep 13 12:26:24.913 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IM op CAPS ADD for tunnel-te0 (ifhndl 0x1c) 
Sep 13 12:26:24.913 mpls_te/head-end 0/RP0/CPU0 t4320  Batch op CAPS ADD with 1 items
Sep 13 12:26:24.913 mpls_te/head-end 0/RP0/CPU0 t4320  ifh:0x1c, NOTFN CREATE; caps ipv4; proto ipv4;
Sep 13 12:26:24.913 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IP address is now available
Sep 13 12:26:24.913 mpls_te/head-end 0/RP0/CPU0 t4320  Batch op MTU UPDATE with 1 items
Sep 13 12:26:24.913 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, handling vif (0xe2067d38) scheduled action ACT_CHECK
Sep 13 12:26:25.004 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IM op CAPS ADD for tunnel-te0 (ifhndl 0x1c) 
Sep 13 12:26:25.123 mpls_te/head-end 0/RP0/CPU0 t4320  tunnel-te0 (ifhndl 0x1c) : queued IM dest update: 7.7.7.7
Sep 13 12:26:25.123 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, Send IM attribute: old dest = 0.0.0.0, new dest = 7.7.7.7
Sep 13 12:26:25.123 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, Start sync retry timer
Sep 13 12:26:25.124 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, Start sync retry timer
Sep 13 12:26:25.124 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE,  POIndex:10, POType:1, ComputationType:0, Holddown:0
Sep 13 12:26:25.127 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, handling vif (0xe2067d38) scheduled action ACT_CHECK
Sep 13 12:26:25.127 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, handling vif (0xe2067d38) scheduled action ACT_MODIFY_IN_PLACE
Sep 13 12:26:25.231 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, L:2, LL:24012, TE rewrite queueing succeeded
Sep 13 12:26:25.232 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, [CURRENT] L:2, ifh:0x1c, TE rewrite returned TRUE
Sep 13 12:26:25.232 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IFH:0x01C, moving state to up
Sep 13 12:26:25.233 mpls_te/head-end 0/RP0/CPU0 t4320  Batch op STATE UPDATE with 1 items
Sep 13 12:26:25.233 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IM op STATE UPDATE for tunnel-te0 (ifhndl 0x1c) 
Sep 13 12:26:25.903 mpls_te/head-end 0/RP0/CPU0 t4320  Validating ifindexes for all vifs
Sep 13 12:26:25.903 mpls_te/head-end 0/RP0/CPU0 t4320  Bulk ifindex lookup for 1 vifs
Sep 13 12:26:25.906 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, ifindex set to 9
Sep 13 12:26:40.124 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, Sync retry timer expired
Sep 13 12:34:11.002 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, L:2, LL:24012, TE rewrite delete queueing succeeded
Sep 13 12:34:11.002 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IFH:0x01C, moving state to down
Sep 13 12:34:11.005 mpls_te/head-end 0/RP0/CPU0 t4320  Batch op STATE UPDATE with 1 items
Sep 13 12:34:11.006 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IM op STATE UPDATE for tunnel-te0 (ifhndl 0x1c) 
Sep 13 12:34:11.007 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, handling vif (0xe2067d38) scheduled action ACT_CHECK
Sep 13 12:34:11.423 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, Start sync retry timer
Sep 13 12:34:11.423 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE,  POIndex:10, POType:2, ComputationType:0, Holddown:0
Sep 13 12:34:11.424 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, Start sync retry timer
Sep 13 12:34:11.424 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, L:3, LL:1048577, TE rewrite delete queueing succeeded
Sep 13 12:34:11.424 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IFH:0x01C, moving state to down
Sep 13 12:34:11.424 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE,  POIndex:10, POType:2, ComputationType:0, Holddown:0
Sep 13 12:34:11.426 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, handling vif (0xe2067d38) scheduled action ACT_CHECK
Sep 13 12:34:11.427 mpls_te/head-end 0/RP0/CPU0 t4320  Batch op STATE UPDATE with 1 items
Sep 13 12:34:11.429 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IM op STATE UPDATE for tunnel-te0 (ifhndl 0x1c) 
Sep 13 12:34:11.601 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, L:4, LL:24012, TE rewrite queueing succeeded
Sep 13 12:34:11.602 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, [CURRENT] L:4, ifh:0x1c, TE rewrite returned TRUE
Sep 13 12:34:11.602 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IFH:0x01C, moving state to up
Sep 13 12:34:11.602 mpls_te/head-end 0/RP0/CPU0 t4320  Batch op STATE UPDATE with 1 items
Sep 13 12:34:11.603 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, IM op STATE UPDATE for tunnel-te0 (ifhndl 0x1c) 
Sep 13 12:34:12.421 mpls_te/head-end 0/RP0/CPU0 t4320  Validating ifindexes for all vifs
Sep 13 12:34:26.424 mpls_te/head-end 0/RP0/CPU0 t4320  T:0, Type:TE, Sync retry timer expired
RP/0/RP0/CPU0:PE1#show mpls traffic-eng trace link
Sun Sep 13 12:41:17.838 UTC
199 wrapping entries (67648 possible, 320 allocated, 0 filtered, 199 total)
Sep 13 12:12:41.305 mpls_te/link 0/RP0/CPU0 t4320  DS-TE mode change: prev - 0, new - 1
Sep 13 12:12:41.305 mpls_te/link 0/RP0/CPU0 t4320  TE process restarting; aborting DS-TE mode change
Sep 13 12:12:42.010 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:381: lm_iarm_control_cb_fn: connection to IARM established.
Sep 13 12:12:45.200 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry
Sep 13 12:12:45.201 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/1 (ifh 0x18) into db based on name and handle
Sep 13 12:12:45.201 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x18), flood 0, force 0, Nbr cnt 0
Sep 13 12:12:45.201 mpls_te/link 0/RP0/CPU0 t4320  RSI: Registering interface GigabitEthernet0/0/0/1 (ifhndl 0x18)  (0x18) for SRLG Notification
Sep 13 12:12:45.201 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/1 (ifh 0x18) Created [1 links total], enable 0
Sep 13 12:12:45.204 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) BW attribute 0 kbps
Sep 13 12:12:45.204 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) physical_bw 125000000 Bps
Sep 13 12:12:45.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps)
Sep 13 12:12:45.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps)
Sep 13 12:12:45.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x18), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0
Sep 13 12:12:45.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x18), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0
Sep 13 12:12:45.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x18), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0
Sep 13 12:12:45.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x18), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0
Sep 13 12:12:45.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/1 (ifh 0x18) state down
Sep 13 12:12:45.212 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/1 (ifh 0x18) to IARM batch
Sep 13 12:12:45.212 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:756: lm_iarm_reg_ipv4_add: sent pulse (0) to flush IARM IPv4 registration batch.
Sep 13 12:12:45.213 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry
Sep 13 12:12:45.213 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/2 (ifh 0x20) into db based on name and handle
Sep 13 12:12:45.213 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x20), flood 0, force 0, Nbr cnt 0
Sep 13 12:12:45.213 mpls_te/link 0/RP0/CPU0 t4320  RSI: Registering interface GigabitEthernet0/0/0/2 (ifhndl 0x20)  (0x20) for SRLG Notification
Sep 13 12:12:45.213 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/2 (ifh 0x20) Created [2 links total], enable 0
Sep 13 12:12:45.214 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x20) BW attribute 0 kbps
Sep 13 12:12:45.214 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x20) physical_bw 125000000 Bps
Sep 13 12:12:45.217 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps)
Sep 13 12:12:45.217 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) pool1_bw 0 (Kbps)
Sep 13 12:12:45.217 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x20), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0
Sep 13 12:12:45.217 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x20), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0
Sep 13 12:12:45.217 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x20), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0
Sep 13 12:12:45.217 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x20), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0
Sep 13 12:12:45.217 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/2 (ifh 0x20) state down
Sep 13 12:12:45.222 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/2 (ifh 0x20) to IARM batch
Sep 13 12:12:45.222 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), state 17, proto 0, opcode 35 (CREATE/DEL)
Sep 13 12:12:45.222 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), intf_type 15, parent i/f None (ifh 0x0), caps 0
Sep 13 12:12:45.222 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/1 (ifh 0x18), state 17, proto 0, opcode 35
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/1 (ifh 0x18), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE)
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) BW attribute 0 kbps
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) physical_bw 125000000 Bps
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps)
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps)
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 0 (Kbps), pool0_bw 0 (Kbps)
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps)
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x18) suppressed, system not ready
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x20), state 17, proto 0, opcode 35 (CREATE/DEL)
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x20), intf_type 15, parent i/f None (ifh 0x0), caps 0
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/2 (ifh 0x20), state 17, proto 0, opcode 35
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/2 (ifh 0x20), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE)
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x20) BW attribute 0 kbps
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x20) physical_bw 125000000 Bps
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps)
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) pool1_bw 0 (Kbps)
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) max_bw 0 (Kbps), pool0_bw 0 (Kbps)
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) pool1_bw 0 (Kbps)
Sep 13 12:12:45.223 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x20) suppressed, system not ready
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  RSI: Synchronous batch handler: 2 items
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  RSI: Event Update with 0 SRLG values for GigabitEthernet0/0/0/1 (ifhndl 0x18)  (0x18)     [change: NO, old count 0]
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  RSI: Event Update with 0 SRLG values for GigabitEthernet0/0/0/2 (ifhndl 0x20)  (0x20)     [change: NO, old count 0]
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  RSI: Sent registration for 2 interfaces (Success 2, failure 0)
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_im_attr_capacity_handler: link MgmtEth0/RP0/CPU0/0 (ifh 0x8),bw 1000000 (kbps), bw2 125000000 (Bps), event 0
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/0 (ifh 0x10),bw 1000000 (kbps), bw2 125000000 (Bps), event 0
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/1 (ifh 0x18),bw 1000000 (kbps), bw2 125000000 (Bps), event 0
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_process_link_capacity(): capacity not changed link GigabitEthernet0/0/0/1 (ifh 0x18), current bw 125000000 (Kbps), new bw 125000000 (Kbps)
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps)
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps)
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 0 (Kbps), pool0_bw 0 (Kbps)
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps)
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/2 (ifh 0x20),bw 1000000 (kbps), bw2 125000000 (Bps), event 0
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_process_link_capacity(): capacity not changed link GigabitEthernet0/0/0/2 (ifh 0x20), current bw 125000000 (Kbps), new bw 125000000 (Kbps)
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps)
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) pool1_bw 0 (Kbps)
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) max_bw 0 (Kbps), pool0_bw 0 (Kbps)
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) pool1_bw 0 (Kbps)
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/3 (ifh 0x28),bw 1000000 (kbps), bw2 125000000 (Bps), event 0
Sep 13 12:12:45.224 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:279: lm_iarm_handle_pulse: received pulse (0), flushing IARM IPv4 registration batch.
Sep 13 12:12:45.225 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:623: lm_iarm_flush: sent 2 IPv4 (un)register requests to IARM.
Sep 13 12:12:45.628 mpls_te/link 0/RP0/CPU0 t4320  SRLG-producer connected
Sep 13 12:12:45.696 mpls_te/link 0/RP0/CPU0 t4320  RSI SRLG-producer registration done successfully
Sep 13 12:12:45.696 mpls_te/link 0/RP0/CPU0 t4320  Replaying learned SRLGs on all links to RSI
Sep 13 12:12:45.696 mpls_te/link 0/RP0/CPU0 t4320  Replaying learned SRLGs on all termination interfaces to RSI
Sep 13 12:12:46.206 mpls_te/link 0/RP0/CPU0 t4320  Validating ifindexes for all links
Sep 13 12:12:46.206 mpls_te/link 0/RP0/CPU0 t4320  Bulk ifindex lookup for 2 links
Sep 13 12:12:46.208 mpls_te/link 0/RP0/CPU0 t4320  Link GigabitEthernet0/0/0/1 (ifhndl 0x18)  (0x18) ifindex set to 5
Sep 13 12:12:46.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x18), flood 0, force 1, Nbr cnt 0
Sep 13 12:12:46.208 mpls_te/link 0/RP0/CPU0 t4320  Link GigabitEthernet0/0/0/2 (ifhndl 0x20)  (0x20) ifindex set to 6
Sep 13 12:12:46.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x20), flood 0, force 1, Nbr cnt 0
Sep 13 12:12:47.098 mpls_te/link 0/RP0/CPU0 t4320  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 12:12:47.098 mpls_te/link 0/RP0/CPU0 t4320  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 12:12:48.913 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2611: Handling area change: IGP OSPF area 0, is_up = 1, router-id 2.2.2.2
Sep 13 12:12:48.913 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x18), flood 0, force 0, Nbr cnt 0
Sep 13 12:12:48.913 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x20), flood 0, force 0, Nbr cnt 0
Sep 13 12:12:48.913 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5406: Periodic Flooding for igp-type: 2 area: 0 0 seconds
Sep 13 12:12:48.993 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5214: System change: flooding to all links/areas: reason area state change
Sep 13 12:12:51.498 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), state 17, proto 12, opcode 35 (CREATE/DEL)
Sep 13 12:12:51.498 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), intf_type 15, parent i/f None (ifh 0x0), caps 26
Sep 13 12:12:51.498 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/1 (ifh 0x18), state 17, proto 12, opcode 35
Sep 13 12:12:51.499 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/1 (ifh 0x18), add True
Sep 13 12:12:51.499 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/1 (ifh 0x18) to IARM batch
Sep 13 12:12:51.499 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:756: lm_iarm_reg_ipv4_add: sent pulse (0) to flush IARM IPv4 registration batch.
Sep 13 12:12:51.499 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/1 (ifh 0x18), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE)
Sep 13 12:12:51.499 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) BW attribute 0 kbps
Sep 13 12:12:51.499 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) physical_bw 125000000 Bps
Sep 13 12:12:51.499 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps)
Sep 13 12:12:51.499 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps)
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 0 (Kbps), pool0_bw 0 (Kbps)
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps)
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x18) suppressed, no change
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x20), state 17, proto 12, opcode 35 (CREATE/DEL)
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x20), intf_type 15, parent i/f None (ifh 0x0), caps 26
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/2 (ifh 0x20), state 17, proto 12, opcode 35
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/2 (ifh 0x20), add True
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/2 (ifh 0x20) to IARM batch
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/2 (ifh 0x20), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE)
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x20) BW attribute 0 kbps
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x20) physical_bw 125000000 Bps
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps)
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) pool1_bw 0 (Kbps)
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) max_bw 0 (Kbps), pool0_bw 0 (Kbps)
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x20) pool1_bw 0 (Kbps)
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x20) suppressed, no change
Sep 13 12:12:51.500 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), state 1, proto 12, caps 26, opcode=STATE_VALUE
Sep 13 12:12:51.501 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x18), state 1, proto 12, opcode 37
Sep 13 12:12:51.501 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x20), state 1, proto 12, caps 26, opcode=STATE_VALUE
Sep 13 12:12:51.501 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x20), state 1, proto 12, opcode 37
Sep 13 12:12:51.501 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:279: lm_iarm_handle_pulse: received pulse (0), flushing IARM IPv4 registration batch.
Sep 13 12:12:51.501 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:623: lm_iarm_flush: sent 2 IPv4 (un)register requests to IARM.
Sep 13 12:12:51.597 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/1 (ifh 0x18), ct=0x0, ps=0x0, addr 10.2.3.2
Sep 13 12:12:51.597 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/2 (ifh 0x20), ct=0x0, ps=0x0, addr 10.2.5.2
Sep 13 12:12:51.916 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), state 2, proto 12, caps 26, opcode=STATE_VALUE
Sep 13 12:12:51.916 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x18), state 2, proto 12, opcode 37
Sep 13 12:12:51.916 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x20), state 2, proto 12, caps 26, opcode=STATE_VALUE
Sep 13 12:12:51.916 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x20), state 2, proto 12, opcode 37
Sep 13 12:12:52.196 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), state 3, proto 12, caps 26, opcode=STATE_VALUE
Sep 13 12:12:52.196 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x18), state 3, proto 12, opcode 37
Sep 13 12:12:52.196 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x18), flood 0, force 0, Nbr cnt 0
Sep 13 12:12:52.196 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/1 (ifh 0x18), area 0, IGP OSPF
Sep 13 12:12:52.200 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x20), state 3, proto 12, caps 26, opcode=STATE_VALUE
Sep 13 12:12:52.200 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x20), state 3, proto 12, opcode 37
Sep 13 12:12:52.200 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x20), flood 0, force 0, Nbr cnt 0
Sep 13 12:12:52.200 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/2 (ifh 0x20), area 0, IGP OSPF
Sep 13 12:12:52.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF
Sep 13 12:12:52.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x18), tot_nbrs 0, subnet type 0, flags 0x2
Sep 13 12:12:52.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x18)  new igp admin weight 10, prev. isis wt 0, ospf wt 0
Sep 13 12:12:52.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:935: Link GigabitEthernet0/0/0/1 (ifh 0x18): Added area 0 IGP OSPF
Sep 13 12:12:52.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1019: Link GigabitEthernet0/0/0/1 (ifh 0x18): Update nbrs for area 0 IGP OSPF
Sep 13 12:12:52.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/1 (ifh 0x18), area 0, protocol OSPF, weight 10
Sep 13 12:12:52.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2523: link GigabitEthernet0/0/0/1 (ifh 0x18), IGP OSPF area 0: no neighbor changes
Sep 13 12:12:52.208 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x18) data changed
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x20) (0 nbrs) in area 0, IGP OSPF
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/2 (ifh 0x20), tot_nbrs 0, subnet type 0, flags 0x2
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x20)  new igp admin weight 20, prev. isis wt 0, ospf wt 0
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:935: Link GigabitEthernet0/0/0/2 (ifh 0x20): Added area 0 IGP OSPF
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1019: Link GigabitEthernet0/0/0/2 (ifh 0x20): Update nbrs for area 0 IGP OSPF
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/2 (ifh 0x20), area 0, protocol OSPF, weight 20
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2523: link GigabitEthernet0/0/0/2 (ifh 0x20), IGP OSPF area 0: no neighbor changes
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x20) data changed
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x18), tot_nbrs 0, subnet type 0, flags 0x2
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x18)  new igp admin weight 10, prev. isis wt 0, ospf wt 0
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2523: link GigabitEthernet0/0/0/1 (ifh 0x18), IGP OSPF area 0: no neighbor changes
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x20) (0 nbrs) in area 0, IGP OSPF
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/2 (ifh 0x20), tot_nbrs 0, subnet type 0, flags 0x2
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x20)  new igp admin weight 20, prev. isis wt 0, ospf wt 0
Sep 13 12:12:52.302 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2523: link GigabitEthernet0/0/0/2 (ifh 0x20), IGP OSPF area 0: no neighbor changes
Sep 13 12:12:52.597 mpls_te/link 0/RP0/CPU0 t4320  Validating ifindexes for all links
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x18), tot_nbrs 0, subnet type 1, flags 0xb
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1019: Link GigabitEthernet0/0/0/1 (ifh 0x18): Update nbrs for area 0 IGP OSPF
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/1 (ifh 0x18), area 0, protocol 2, subnet type 1
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x18)  new igp admin weight 10, prev. isis wt 0, ospf wt 0
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2498: Link GigabitEthernet0/0/0/1 (ifh 0x18): update DB for 1 neighbors
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1559: Received neighbor 10.2.3.3 on link GigabitEthernet0/0/0/1 (ifh 0x18), IGP OSPF area 0
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1578: Rcvd nbr node ID 3.3.3.3, state 1
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1596: Matching existing neighbor found? False
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1714: Neighbor unknown: creating new one
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1286: link GigabitEthernet0/0/0/1 (ifh 0x18): setting IGP cost: 10, in area 0, protocol OSPF
Sep 13 12:13:01.884 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1429: link GigabitEthernet0/0/0/1 (ifh 0x18), IGP OSPF area 0: subnet type changed to 1
Sep 13 12:13:01.885 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/1 (ifh 0x18) nbr addr 10.2.3.3, count 1, nbr state 1
Sep 13 12:13:01.885 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x18) data changed
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x20) (0 nbrs) in area 0, IGP OSPF
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/2 (ifh 0x20), tot_nbrs 0, subnet type 1, flags 0xb
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1019: Link GigabitEthernet0/0/0/2 (ifh 0x20): Update nbrs for area 0 IGP OSPF
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/2 (ifh 0x20), area 0, protocol 2, subnet type 1
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x20)  new igp admin weight 20, prev. isis wt 0, ospf wt 0
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:2498: Link GigabitEthernet0/0/0/2 (ifh 0x20): update DB for 1 neighbors
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1559: Received neighbor 10.2.5.5 on link GigabitEthernet0/0/0/2 (ifh 0x20), IGP OSPF area 0
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1578: Rcvd nbr node ID 5.5.5.5, state 1
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1596: Matching existing neighbor found? False
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1714: Neighbor unknown: creating new one
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1286: link GigabitEthernet0/0/0/2 (ifh 0x20): setting IGP cost: 20, in area 0, protocol OSPF
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1429: link GigabitEthernet0/0/0/2 (ifh 0x20), IGP OSPF area 0: subnet type changed to 1
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/2 (ifh 0x20) nbr addr 10.2.5.5, count 2, nbr state 1
Sep 13 12:13:11.819 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x20) data changed
Sep 13 12:15:45.398 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:5471: Forced Flooding for IGP OSPF, area 0
Sep 13 12:26:24.913 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK: rrr_lm_im_attr_capacity_handler: link tunnel-te0 (ifh 0x1c),bw 0 (kbps), bw2 0 (Bps), event 0
Sep 13 12:26:24.913 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3722: lm_im_handler: link tunnel-te0 (ifh 0x1c), state 17, proto 12, opcode 35 (CREATE/DEL)
Sep 13 12:26:24.913 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3727: lm_im_handler: link tunnel-te0 (ifh 0x1c), intf_type 36, parent i/f None (ifh 0x0), caps 26
Sep 13 12:26:24.913 mpls_te/link 0/RP0/CPU0 t4320  LM_LINK:3808: lm_im_handler: IM_CREATE: link tunnel-te0 (ifh 0x1c), state 17, proto 12, opcode 35
RP/0/RP0/CPU0:PE1#show rsvp trace signalling
Sun Sep 13 12:41:18.040 UTC
23 wrapping entries (264256 possible, 64 allocated, 0 filtered, 23 total)
Sep 13 12:26:25.134 rsvp/sig 0/RP0/CPU0 t4152  SIG:4545: PATH creating head: local/nbor/nhop (2.2.2.2/3.3.3.3/10.2.3.3), GigabitEthernet0/0/0/1 (ifh 0x18), psbs: 0, rsbs: 0
Sep 13 12:26:25.134 rsvp/sig 0/RP0/CPU0 t4152  SIG:2836: PATH outgoing creating: flags:0xc0000005, local_rid:2.2.2.2, nbor:3.3.3.3, nhop:10.2.3.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x18), obj_len: 0
Sep 13 12:26:25.135 rsvp/sig 0/RP0/CPU0 t4152  SIG:887: PATH Tunnel IPv4 outgoing: dst (7.7.7.7:0), src (2.2.2.2:2)
Sep 13 12:26:25.136 rsvp/sig 0/RP0/CPU0 t4152  SIG:721: PATH Tunnel IPv4 created: dst (7.7.7.7:0), src (2.2.2.2:2)
Sep 13 12:26:25.221 rsvp/sig 0/RP0/CPU0 t4152  SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x18), in GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 140, IP src: 10.2.3.3, psbs: 1, rsbs: 0
Sep 13 12:26:25.222 rsvp/sig 0/RP0/CPU0 t4152  TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 0, src 2.2.2.2 id 2
Sep 13 12:26:25.222 rsvp/sig 0/RP0/CPU0 t4152  SIG:2881: RESV outgoing creating: rsb flags: 0xc0000011, psb flags: 0xc0000004, local/nbor 2.2.2.2/0.0.0.0, obj len: 0x8c, wedged 0
Sep 13 12:26:25.222 rsvp/sig 0/RP0/CPU0 t4152  SIG:362: RESV Tunnel IPv4 created: dst (7.7.7.7:0), src (2.2.2.2:2)
Sep 13 12:26:25.234 rsvp/sig 0/RP0/CPU0 t4152  SIG:492: Assigned backup None (ifh 0x0): dst (7.7.7.7:0), src (2.2.2.2:2) 
Sep 13 12:34:11.009 rsvp/sig 0/RP0/CPU0 t4152  SIG:3929: PATH destroy: dst (7.7.7.7:0), src (2.2.2.2:2) reason: (5): State deleted due to client app
Sep 13 12:34:11.009 rsvp/sig 0/RP0/CPU0 t4152  SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000004, pfc flags 0x80180009
Sep 13 12:34:11.009 rsvp/sig 0/RP0/CPU0 t4152  SIG:1214: RESV destroy: dst (7.7.7.7:0), src (2.2.2.2:2) reason: (5): State deleted due to client app
Sep 13 12:34:11.009 rsvp/sig 0/RP0/CPU0 t4152  SIG:1216: RESV destroy: flags 0x180000, rsb flags 0xc0000030, request flags 0x0
Sep 13 12:34:11.009 rsvp/sig 0/RP0/CPU0 t4152  TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 0, src 2.2.2.2 id 2
Sep 13 12:34:11.432 rsvp/sig 0/RP0/CPU0 t4152  SIG:4545: PATH creating head: local/nbor/nhop (2.2.2.2/5.5.5.5/10.2.5.5), GigabitEthernet0/0/0/2 (ifh 0x20), psbs: 0, rsbs: 0
Sep 13 12:34:11.432 rsvp/sig 0/RP0/CPU0 t4152  SIG:2836: PATH outgoing creating: flags:0xc0000005, local_rid:2.2.2.2, nbor:5.5.5.5, nhop:10.2.5.5, next_ifh:GigabitEthernet0/0/0/2 (ifh 0x20), obj_len: 0
Sep 13 12:34:11.432 rsvp/sig 0/RP0/CPU0 t4152  SIG:887: PATH Tunnel IPv4 outgoing: dst (7.7.7.7:0), src (2.2.2.2:4)
Sep 13 12:34:11.432 rsvp/sig 0/RP0/CPU0 t4152  SIG:721: PATH Tunnel IPv4 created: dst (7.7.7.7:0), src (2.2.2.2:4)
Sep 13 12:34:11.595 rsvp/sig 0/RP0/CPU0 t4152  SIG:4654: RESV creating network: GigabitEthernet0/0/0/2 (ifh 0x20), in GigabitEthernet0/0/0/2 (ifh 0x20), obj len: 212, IP src: 10.2.5.5, psbs: 1, rsbs: 0
Sep 13 12:34:11.596 rsvp/sig 0/RP0/CPU0 t4152  TC:276: Bandwidth allocation: GigabitEthernet0/0/0/2 (ifh 0x20), current (0 Bps), port 0, src 2.2.2.2 id 4
Sep 13 12:34:11.596 rsvp/sig 0/RP0/CPU0 t4152  SIG:2881: RESV outgoing creating: rsb flags: 0xc0000011, psb flags: 0xc0000004, local/nbor 2.2.2.2/0.0.0.0, obj len: 0xd4, wedged 0
Sep 13 12:34:11.596 rsvp/sig 0/RP0/CPU0 t4152  SIG:362: RESV Tunnel IPv4 created: dst (7.7.7.7:0), src (2.2.2.2:4)
Sep 13 12:34:11.604 rsvp/sig 0/RP0/CPU0 t4152  SIG:492: Assigned backup None (ifh 0x0): dst (7.7.7.7:0), src (2.2.2.2:4)