RP/0/RP0/CPU0:P2#show mpls traffic-eng trace head-end Tue Sep 15 03:13:31.160 UTC 39 wrapping entries (264256 possible, 320 allocated, 0 filtered, 39 total) Sep 15 02:28:11.759 mpls_te/head-end 0/RP0/CPU0 t4604 [PCALC-ECMP] Max BWV Allocated: 20 Sep 15 02:28:11.759 mpls_te/head-end 0/RP0/CPU0 t4604 [PCALC-ECMP] Max BWVE Allocated: 15 Sep 15 02:28:13.589 mpls_te/head-end 0/RP0/CPU0 t4604 NOTFN OwnedResEnd Sep 15 02:28:15.048 mpls_te/head-end 0/RP0/CPU0 t4604 Validating ifindexes for all vifs Sep 15 02:59:22.445 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, Start sync retry timer Sep 15 02:59:22.445 mpls_te/head-end 0/RP0/CPU0 t4604 Batch op IF CREATE with 1 items Sep 15 02:59:22.451 mpls_te/head-end 0/RP0/CPU0 t4604 ifh:0x1c, NOTFN INITIAL; caps ; proto NONE; state not ready Sep 15 02:59:22.451 mpls_te/head-end 0/RP0/CPU0 t4604 ifh:0x1c, NOTFN INITIAL; caps mpls_te; proto NONE; state not ready Sep 15 02:59:22.451 mpls_te/head-end 0/RP0/CPU0 t4604 Batch op CAPS ADD with 1 items Sep 15 02:59:22.452 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, ifh:0x1c, NOTFN STATE, caps ; proto NONE; state down Sep 15 02:59:22.453 mpls_te/head-end 0/RP0/CPU0 t4604 Batch op STATE UPDATE with 1 items Sep 15 02:59:22.454 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, IM op STATE UPDATE for tunnel-te100 (ifhndl 0x1c) Sep 15 02:59:22.455 mpls_te/head-end 0/RP0/CPU0 t4604 ifh:0x1c, NOTFN INITIAL; caps mpls; proto mpls; state not ready Sep 15 02:59:22.455 mpls_te/head-end 0/RP0/CPU0 t4604 ifh:0x1c, NOTFN MTU; caps mpls; proto mpls; mtu 1500 Sep 15 02:59:22.455 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, IM op CAPS ADD for tunnel-te100 (ifhndl 0x1c) Sep 15 02:59:22.455 mpls_te/head-end 0/RP0/CPU0 t4604 Batch op CAPS ADD with 1 items Sep 15 02:59:22.455 mpls_te/head-end 0/RP0/CPU0 t4604 Batch op MTU UPDATE with 1 items Sep 15 02:59:22.466 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, IM op CAPS ADD for tunnel-te100 (ifhndl 0x1c) Sep 15 02:59:22.547 mpls_te/head-end 0/RP0/CPU0 t4604 ifh:0x1c, NOTFN CREATE; caps ipv4; proto ipv4; Sep 15 02:59:22.547 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, IP address is now available Sep 15 02:59:22.547 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, handling vif (0x79207d38) scheduled action ACT_CHECK Sep 15 02:59:22.585 mpls_te/head-end 0/RP0/CPU0 t4604 tunnel-te100 (ifhndl 0x1c) : queued IM dest update: 1.1.1.1 Sep 15 02:59:22.585 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, Send IM attribute: old dest = 0.0.0.0, new dest = 1.1.1.1 Sep 15 02:59:22.585 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, Start sync retry timer Sep 15 02:59:22.586 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, Start sync retry timer Sep 15 02:59:22.586 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, POIndex:10, POType:2, ComputationType:0, Holddown:0 Sep 15 02:59:22.588 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, handling vif (0x79207d38) scheduled action ACT_CHECK Sep 15 02:59:22.655 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, L:2, LL:24004, TE rewrite queueing succeeded Sep 15 02:59:22.656 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, [CURRENT] L:2, ifh:0x1c, TE rewrite returned TRUE Sep 15 02:59:22.656 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, IFH:0x01C, moving state to up Sep 15 02:59:22.657 mpls_te/head-end 0/RP0/CPU0 t4604 Batch op STATE UPDATE with 1 items Sep 15 02:59:22.658 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, IM op STATE UPDATE for tunnel-te100 (ifhndl 0x1c) Sep 15 02:59:23.445 mpls_te/head-end 0/RP0/CPU0 t4604 Validating ifindexes for all vifs Sep 15 02:59:23.445 mpls_te/head-end 0/RP0/CPU0 t4604 Bulk ifindex lookup for 1 vifs Sep 15 02:59:23.447 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, ifindex set to 9 Sep 15 02:59:37.587 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, Sync retry timer expired Sep 15 03:06:40.089 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, backup reoptimization disallowed Sep 15 03:06:40.145 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, SRV: reoptimize holddown timer started for 600 secs Sep 15 03:07:23.690 mpls_te/head-end 0/RP0/CPU0 t4604 T:100, Type:TE, backup reoptimization allowed RP/0/RP0/CPU0:P2#show mpls traffic-eng trace link Tue Sep 15 03:13:31.419 UTC 337 wrapping entries (67648 possible, 576 allocated, 0 filtered, 337 total) Sep 15 02:28:11.759 mpls_te/link 0/RP0/CPU0 t4604 DS-TE mode change: prev - 0, new - 1 Sep 15 02:28:11.759 mpls_te/link 0/RP0/CPU0 t4604 TE process restarting; aborting DS-TE mode change Sep 15 02:28:12.866 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:381: lm_iarm_control_cb_fn: connection to IARM established. Sep 15 02:28:13.762 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry Sep 15 02:28:13.762 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/0 (ifh 0x8) into db based on name and handle Sep 15 02:28:13.762 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x8), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:13.762 mpls_te/link 0/RP0/CPU0 t4604 RSI: Registering interface GigabitEthernet0/0/0/0 (ifhndl 0x8) (0x8) for SRLG Notification Sep 15 02:28:13.762 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/0 (ifh 0x8) Created [1 links total], enable 0 Sep 15 02:28:13.764 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) BW attribute 0 kbps Sep 15 02:28:13.764 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) physical_bw 125000000 Bps Sep 15 02:28:13.767 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.767 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 15 02:28:13.767 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.767 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.767 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.767 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.767 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/0 (ifh 0x8) state down Sep 15 02:28:13.770 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.770 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:756: lm_iarm_reg_ipv4_add: sent pulse (0) to flush IARM IPv4 registration batch. Sep 15 02:28:13.771 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry Sep 15 02:28:13.771 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/1 (ifh 0x10) into db based on name and handle Sep 15 02:28:13.771 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x10), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:13.771 mpls_te/link 0/RP0/CPU0 t4604 RSI: Registering interface GigabitEthernet0/0/0/1 (ifhndl 0x10) (0x10) for SRLG Notification Sep 15 02:28:13.771 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/1 (ifh 0x10) Created [2 links total], enable 0 Sep 15 02:28:13.772 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) BW attribute 0 kbps Sep 15 02:28:13.772 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) physical_bw 125000000 Bps Sep 15 02:28:13.775 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:13.775 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:13.775 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x10), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:13.775 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x10), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:13.775 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x10), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:13.775 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x10), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:13.775 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/1 (ifh 0x10) state down Sep 15 02:28:13.778 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.779 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry Sep 15 02:28:13.779 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/2 (ifh 0x18) into db based on name and handle Sep 15 02:28:13.779 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x18), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:13.779 mpls_te/link 0/RP0/CPU0 t4604 RSI: Registering interface GigabitEthernet0/0/0/2 (ifhndl 0x18) (0x18) for SRLG Notification Sep 15 02:28:13.779 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/2 (ifh 0x18) Created [3 links total], enable 0 Sep 15 02:28:13.780 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) BW attribute 0 kbps Sep 15 02:28:13.780 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) physical_bw 125000000 Bps Sep 15 02:28:13.782 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.782 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:13.782 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.782 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.782 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.782 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.782 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/2 (ifh 0x18) state down Sep 15 02:28:13.786 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.786 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 17, proto 0, opcode 35 (CREATE/DEL) Sep 15 02:28:13.786 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.786 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/0 (ifh 0x8), state 17, proto 0, opcode 35 Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) BW attribute 0 kbps Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) physical_bw 125000000 Bps Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x8) suppressed, system not ready Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), state 17, proto 0, opcode 35 (CREATE/DEL) Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/1 (ifh 0x10), state 17, proto 0, opcode 35 Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) BW attribute 0 kbps Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) physical_bw 125000000 Bps Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x10) suppressed, system not ready Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), state 17, proto 0, opcode 35 (CREATE/DEL) Sep 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.787 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/2 (ifh 0x18), state 17, proto 0, opcode 35 Sep 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) BW attribute 0 kbps Sep 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) physical_bw 125000000 Bps Sep 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x18) suppressed, system not ready Sep 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 RSI: Synchronous batch handler: 3 items Sep 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 RSI: Event Update with 0 SRLG values for GigabitEthernet0/0/0/0 (ifhndl 0x8) (0x8) [change: NO, old count 0] Sep 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 RSI: Event Update with 0 SRLG values for GigabitEthernet0/0/0/1 (ifhndl 0x10) (0x10) [change: NO, old count 0] Sep 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 RSI: Event Update with 0 SRLG values for GigabitEthernet0/0/0/2 (ifhndl 0x18) (0x18) [change: NO, old count 0] Sep 15 02:28:13.788 mpls_te/link 0/RP0/CPU0 t4604 RSI: Sent registration for 3 interfaces (Success 3, failure 0) Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/0 (ifh 0x8),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/1 (ifh 0x10),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/2 (ifh 0x18),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/3 (ifh 0x20),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_im_attr_capacity_handler: link MgmtEth0/RP0/CPU0/0 (ifh 0x28),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 15 02:28:13.789 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:279: lm_iarm_handle_pulse: received pulse (0), flushing IARM IPv4 registration batch. Sep 15 02:28:13.790 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:623: lm_iarm_flush: sent 3 IPv4 (un)register requests to IARM. Sep 15 02:28:14.246 mpls_te/link 0/RP0/CPU0 t4604 SRLG-producer connected Sep 15 02:28:14.247 mpls_te/link 0/RP0/CPU0 t4604 RSI SRLG-producer registration done successfully Sep 15 02:28:14.247 mpls_te/link 0/RP0/CPU0 t4604 Replaying learned SRLGs on all links to RSI Sep 15 02:28:14.247 mpls_te/link 0/RP0/CPU0 t4604 Replaying learned SRLGs on all termination interfaces to RSI Sep 15 02:28:14.763 mpls_te/link 0/RP0/CPU0 t4604 Validating ifindexes for all links Sep 15 02:28:14.763 mpls_te/link 0/RP0/CPU0 t4604 Bulk ifindex lookup for 3 links Sep 15 02:28:14.765 mpls_te/link 0/RP0/CPU0 t4604 Link GigabitEthernet0/0/0/0 (ifhndl 0x8) (0x8) ifindex set to 3 Sep 15 02:28:14.765 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x8), flood 0, force 1, Nbr cnt 0 Sep 15 02:28:14.765 mpls_te/link 0/RP0/CPU0 t4604 Link GigabitEthernet0/0/0/1 (ifhndl 0x10) (0x10) ifindex set to 4 Sep 15 02:28:14.765 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x10), flood 0, force 1, Nbr cnt 0 Sep 15 02:28:14.765 mpls_te/link 0/RP0/CPU0 t4604 Link GigabitEthernet0/0/0/2 (ifhndl 0x18) (0x18) ifindex set to 5 Sep 15 02:28:14.765 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x18), flood 0, force 1, Nbr cnt 0 Sep 15 02:28:15.047 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:119: Unable to process adj-change from IGP OSPFarea 0 on link Loopback0 (ifh 0x14) with handle 0x14, reverting to name Sep 15 02:28:15.047 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:145: Unable to process adj-change from IGP OSPF area 0 on link Loopback0 (ifh 0x14) (0 nbrs): link not found Sep 15 02:28:16.078 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2611: Handling area change: IGP OSPF area 0, is_up = 1, router-id 2.2.2.2 Sep 15 02:28:16.078 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x8), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:16.078 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x10), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:16.078 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x18), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:16.078 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5406: Periodic Flooding for igp-type: 2 area: 0 0 seconds Sep 15 02:28:16.079 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5214: System change: flooding to all links/areas: reason area state change Sep 15 02:28:18.455 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 15 02:28:18.455 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.455 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/0 (ifh 0x8), state 17, proto 12, opcode 35 Sep 15 02:28:18.456 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/0 (ifh 0x8), add True Sep 15 02:28:18.456 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.456 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:756: lm_iarm_reg_ipv4_add: sent pulse (0) to flush IARM IPv4 registration batch. Sep 15 02:28:18.456 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.456 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) BW attribute 0 kbps Sep 15 02:28:18.456 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/0 (ifh 0x8) physical_bw 125000000 Bps Sep 15 02:28:18.456 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.456 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 15 02:28:18.457 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.457 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x8) pool1_bw 0 (Kbps) Sep 15 02:28:18.457 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x8) suppressed, no change Sep 15 02:28:18.457 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 15 02:28:18.457 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.457 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/1 (ifh 0x10), state 17, proto 12, opcode 35 Sep 15 02:28:18.459 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/1 (ifh 0x10), add True Sep 15 02:28:18.459 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.459 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.460 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) BW attribute 0 kbps Sep 15 02:28:18.460 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x10) physical_bw 125000000 Bps Sep 15 02:28:18.460 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:18.460 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:18.460 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.460 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:18.460 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x10) suppressed, no change Sep 15 02:28:18.460 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 15 02:28:18.460 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.460 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/2 (ifh 0x18), state 17, proto 12, opcode 35 Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/2 (ifh 0x18), add True Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) BW attribute 0 kbps Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x18) physical_bw 125000000 Bps Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x18) suppressed, no change Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x8), state 1, proto 12, opcode 37 Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x10), state 1, proto 12, opcode 37 Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:18.462 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x18), state 1, proto 12, opcode 37 Sep 15 02:28:18.463 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:279: lm_iarm_handle_pulse: received pulse (0), flushing IARM IPv4 registration batch. Sep 15 02:28:18.463 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:623: lm_iarm_flush: sent 3 IPv4 (un)register requests to IARM. Sep 15 02:28:18.547 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/0 (ifh 0x8), ct=0x0, ps=0x0, addr 10.1.2.2 Sep 15 02:28:18.548 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/1 (ifh 0x10), ct=0x0, ps=0x0, addr 10.2.3.2 Sep 15 02:28:18.548 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/2 (ifh 0x18), ct=0x0, ps=0x0, addr 10.2.5.2 Sep 15 02:28:18.952 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:18.952 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x8), state 2, proto 12, opcode 37 Sep 15 02:28:18.952 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:18.952 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x10), state 2, proto 12, opcode 37 Sep 15 02:28:18.952 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:18.952 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x18), state 2, proto 12, opcode 37 Sep 15 02:28:19.159 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:19.159 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x8), state 3, proto 12, opcode 37 Sep 15 02:28:19.159 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x8), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:19.159 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/0 (ifh 0x8), area 0, IGP OSPF Sep 15 02:28:19.251 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x10), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:19.251 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x10), state 3, proto 12, opcode 37 Sep 15 02:28:19.251 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x10), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:19.251 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/1 (ifh 0x10), area 0, IGP OSPF Sep 15 02:28:19.256 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x18), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:19.256 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x18), state 3, proto 12, opcode 37 Sep 15 02:28:19.257 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x18), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:19.257 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/2 (ifh 0x18), area 0, IGP OSPF Sep 15 02:28:19.345 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x8) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:19.345 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:19.345 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x8) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:19.345 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:935: Link GigabitEthernet0/0/0/0 (ifh 0x8): Added area 0 IGP OSPF Sep 15 02:28:19.345 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1019: Link GigabitEthernet0/0/0/0 (ifh 0x8): Update nbrs for area 0 IGP OSPF Sep 15 02:28:19.345 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/0 (ifh 0x8), area 0, protocol OSPF, weight 10 Sep 15 02:28:19.346 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2523: link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0: no neighbor changes Sep 15 02:28:19.346 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x8) data changed Sep 15 02:28:19.353 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x10) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:19.353 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:19.353 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:19.353 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:935: Link GigabitEthernet0/0/0/1 (ifh 0x10): Added area 0 IGP OSPF Sep 15 02:28:19.353 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1019: Link GigabitEthernet0/0/0/1 (ifh 0x10): Update nbrs for area 0 IGP OSPF Sep 15 02:28:19.353 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/1 (ifh 0x10), area 0, protocol OSPF, weight 10 Sep 15 02:28:19.353 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2523: link GigabitEthernet0/0/0/1 (ifh 0x10), IGP OSPF area 0: no neighbor changes Sep 15 02:28:19.353 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x10) data changed Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x18) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:935: Link GigabitEthernet0/0/0/2 (ifh 0x18): Added area 0 IGP OSPF Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1019: Link GigabitEthernet0/0/0/2 (ifh 0x18): Update nbrs for area 0 IGP OSPF Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/2 (ifh 0x18), area 0, protocol OSPF, weight 10 Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2523: link GigabitEthernet0/0/0/2 (ifh 0x18), IGP OSPF area 0: no neighbor changes Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x18) data changed Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x8) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x8) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2523: link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0: no neighbor changes Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x10) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2523: link GigabitEthernet0/0/0/1 (ifh 0x10), IGP OSPF area 0: no neighbor changes Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x18) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:19.363 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2523: link GigabitEthernet0/0/0/2 (ifh 0x18), IGP OSPF area 0: no neighbor changes Sep 15 02:28:19.548 mpls_te/link 0/RP0/CPU0 t4604 Validating ifindexes for all links Sep 15 02:28:20.572 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x8) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:20.572 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:20.572 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1019: Link GigabitEthernet0/0/0/0 (ifh 0x8): Update nbrs for area 0 IGP OSPF Sep 15 02:28:20.572 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/0 (ifh 0x8), area 0, protocol 2, subnet type 1 Sep 15 02:28:20.572 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x8) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:20.572 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2498: Link GigabitEthernet0/0/0/0 (ifh 0x8): update DB for 1 neighbors Sep 15 02:28:20.572 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1559: Received neighbor 10.1.2.1 on link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0 Sep 15 02:28:20.572 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1578: Rcvd nbr node ID 1.1.1.1, state 1 Sep 15 02:28:20.572 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1596: Matching existing neighbor found? False Sep 15 02:28:20.572 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1714: Neighbor unknown: creating new one Sep 15 02:28:20.573 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1286: link GigabitEthernet0/0/0/0 (ifh 0x8): setting IGP cost: 10, in area 0, protocol OSPF Sep 15 02:28:20.573 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1429: link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0: subnet type changed to 1 Sep 15 02:28:20.573 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/0 (ifh 0x8) nbr addr 10.1.2.1, count 1, nbr state 1 Sep 15 02:28:20.573 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x8) data changed Sep 15 02:28:29.164 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x10) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:29.164 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:29.164 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1019: Link GigabitEthernet0/0/0/1 (ifh 0x10): Update nbrs for area 0 IGP OSPF Sep 15 02:28:29.164 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/1 (ifh 0x10), area 0, protocol 2, subnet type 1 Sep 15 02:28:29.165 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:29.165 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2498: Link GigabitEthernet0/0/0/1 (ifh 0x10): update DB for 1 neighbors Sep 15 02:28:29.165 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1559: Received neighbor 10.2.3.3 on link GigabitEthernet0/0/0/1 (ifh 0x10), IGP OSPF area 0 Sep 15 02:28:29.165 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1578: Rcvd nbr node ID 3.3.3.3, state 1 Sep 15 02:28:29.165 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1596: Matching existing neighbor found? False Sep 15 02:28:29.165 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1714: Neighbor unknown: creating new one Sep 15 02:28:29.165 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1286: link GigabitEthernet0/0/0/1 (ifh 0x10): setting IGP cost: 10, in area 0, protocol OSPF Sep 15 02:28:29.165 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1429: link GigabitEthernet0/0/0/1 (ifh 0x10), IGP OSPF area 0: subnet type changed to 1 Sep 15 02:28:29.165 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/1 (ifh 0x10) nbr addr 10.2.3.3, count 2, nbr state 1 Sep 15 02:28:29.165 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x10) data changed Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1019: Link GigabitEthernet0/0/0/2 (ifh 0x18): Update nbrs for area 0 IGP OSPF Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/2 (ifh 0x18), area 0, protocol 2, subnet type 1 Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x18) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2498: Link GigabitEthernet0/0/0/2 (ifh 0x18): update DB for 1 neighbors Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1559: Received neighbor 10.2.5.5 on link GigabitEthernet0/0/0/2 (ifh 0x18), IGP OSPF area 0 Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1578: Rcvd nbr node ID 5.5.5.5, state 1 Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1596: Matching existing neighbor found? False Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1714: Neighbor unknown: creating new one Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1286: link GigabitEthernet0/0/0/2 (ifh 0x18): setting IGP cost: 10, in area 0, protocol OSPF Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1429: link GigabitEthernet0/0/0/2 (ifh 0x18), IGP OSPF area 0: subnet type changed to 1 Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/2 (ifh 0x18) nbr addr 10.2.5.5, count 3, nbr state 1 Sep 15 02:28:57.874 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x18) data changed Sep 15 02:31:13.945 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5471: Forced Flooding for IGP OSPF, area 0 Sep 15 02:42:03.258 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:42:03.258 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x8), state 1, proto 12, opcode 37 Sep 15 02:42:03.258 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5635: rrr_lm_update_igp_link_status: link GigabitEthernet0/0/0/0 (ifh 0x8), delete 0 Sep 15 02:42:03.259 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:18474: Link:10.1.2.2, IGP OSPF, area 0 helddown in topology for 10 secs (due to path error) Sep 15 02:42:03.259 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:332: rrr_lm_bfd_delete_session_p2p: attempt to delete a bfd session to 10.1.2.1 on intf None (ifh 0x0) when none exists Sep 15 02:42:03.259 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1044: Neighbor destroyed link GigabitEthernet0/0/0/0 (ifh 0x8): Nbr 10.1.2.1, area 0 Sep 15 02:42:03.346 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x8) (0 nbrs) in area 0, IGP OSPF Sep 15 02:42:03.346 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:42:03.346 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x8) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:42:03.346 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2498: Link GigabitEthernet0/0/0/0 (ifh 0x8): update DB for 1 neighbors Sep 15 02:42:03.346 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1559: Received neighbor 10.1.2.1 on link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0 Sep 15 02:42:03.346 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1578: Rcvd nbr node ID 1.1.1.1, state 0 Sep 15 02:42:03.346 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1596: Matching existing neighbor found? False Sep 15 02:42:03.346 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x8) data changed Sep 15 02:49:33.450 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:49:33.450 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x8), state 2, proto 12, opcode 37 Sep 15 02:49:33.469 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:49:33.469 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x8), state 3, proto 12, opcode 37 Sep 15 02:49:33.469 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x8), flood 0, force 0, Nbr cnt 0 Sep 15 02:49:33.469 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/0 (ifh 0x8), area 0, IGP OSPF Sep 15 02:49:33.548 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x8) (0 nbrs) in area 0, IGP OSPF Sep 15 02:49:33.548 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:49:33.548 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x8) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:49:33.548 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2523: link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0: no neighbor changes Sep 15 02:49:33.548 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x8) (0 nbrs) in area 0, IGP OSPF Sep 15 02:49:33.548 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:49:33.548 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x8) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:49:33.548 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2523: link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0: no neighbor changes Sep 15 02:49:34.469 mpls_te/link 0/RP0/CPU0 t4604 Validating ifindexes for all links Sep 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x8) (0 nbrs) in area 0, IGP OSPF Sep 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 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 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x8) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2498: Link GigabitEthernet0/0/0/0 (ifh 0x8): update DB for 1 neighbors Sep 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1559: Received neighbor 10.1.2.1 on link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0 Sep 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1578: Rcvd nbr node ID 1.1.1.1, state 1 Sep 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1596: Matching existing neighbor found? False Sep 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1714: Neighbor unknown: creating new one Sep 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1286: link GigabitEthernet0/0/0/0 (ifh 0x8): setting IGP cost: 10, in area 0, protocol OSPF Sep 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1429: link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0: subnet type changed to 1 Sep 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/0 (ifh 0x8) nbr addr 10.1.2.1, count 3, nbr state 1 Sep 15 02:49:36.053 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x8) data changed Sep 15 02:59:22.455 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK: rrr_lm_im_attr_capacity_handler: link tunnel-te100 (ifh 0x1c),bw 0 (kbps), bw2 0 (Bps), event 0 Sep 15 02:59:22.547 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3722: lm_im_handler: link tunnel-te100 (ifh 0x1c), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 15 02:59:22.547 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3727: lm_im_handler: link tunnel-te100 (ifh 0x1c), intf_type 36, parent i/f None (ifh 0x0), caps 26 Sep 15 02:59:22.547 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3808: lm_im_handler: IM_CREATE: link tunnel-te100 (ifh 0x1c), state 17, proto 12, opcode 35 Sep 15 03:06:40.089 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x8), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 15 03:06:40.089 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x8), state 1, proto 12, opcode 37 Sep 15 03:06:40.089 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5635: rrr_lm_update_igp_link_status: link GigabitEthernet0/0/0/0 (ifh 0x8), delete 0 Sep 15 03:06:40.145 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:18474: Link:10.1.2.2, IGP OSPF, area 0 helddown in topology for 10 secs (due to path error) Sep 15 03:06:40.145 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:332: rrr_lm_bfd_delete_session_p2p: attempt to delete a bfd session to 10.1.2.1 on intf None (ifh 0x0) when none exists Sep 15 03:06:40.145 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1044: Neighbor destroyed link GigabitEthernet0/0/0/0 (ifh 0x8): Nbr 10.1.2.1, area 0 Sep 15 03:06:40.146 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x8) (0 nbrs) in area 0, IGP OSPF Sep 15 03:06:40.146 mpls_te/link 0/RP0/CPU0 t4604 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 15 03:06:40.146 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x8) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 03:06:40.146 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:2498: Link GigabitEthernet0/0/0/0 (ifh 0x8): update DB for 1 neighbors Sep 15 03:06:40.146 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1559: Received neighbor 10.1.2.1 on link GigabitEthernet0/0/0/0 (ifh 0x8), IGP OSPF area 0 Sep 15 03:06:40.146 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1578: Rcvd nbr node ID 1.1.1.1, state 0 Sep 15 03:06:40.146 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:1596: Matching existing neighbor found? False Sep 15 03:06:40.146 mpls_te/link 0/RP0/CPU0 t4604 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x8) data changed RP/0/RP0/CPU0:P2#show mpls traffic-eng trace bselect Tue Sep 15 03:13:31.561 UTC 78 wrapping entries (67648 possible, 320 allocated, 0 filtered, 78 total) Sep 15 02:28:16.149 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:20.572 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:20.572 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:20.572 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:20.573 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:20.580 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:20.580 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:29.165 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:29.171 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:29.181 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.074 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.105 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.105 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:49.008 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:49.041 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:49.041 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:53.886 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:53.919 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:53.919 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:57.880 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:57.880 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:58.590 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:58.623 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:58.659 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:58.928 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:58.960 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:30:39.266 mpls_te/bselect 0/RP0/CPU0 t4604 tebm_verify_bkup_db: Verifying existing bkup data Sep 15 02:42:03.262 mpls_te/bselect 0/RP0/CPU0 t4604 frr_event_lsp_down: T:0, L:3, prot_if GigabitEthernet0/0/0/1 (ifhndl 0x10) , NH:10.2.3.3 Sep 15 02:42:03.263 mpls_te/bselect 0/RP0/CPU0 t4604 frr_event_lsp_down: T:0, L:3, prot_if GigabitEthernet0/0/0/0 (ifhndl 0x8) , NH:10.1.2.1 Sep 15 02:49:36.058 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:49:36.062 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:51:09.773 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:6987: frr_event_reroute_added: T:0, L:4, prot_des 0, NH:10.2.3.3 Sep 15 02:51:09.774 mpls_te/bselect 0/RP0/CPU0 t4604 te_frr_add_new_plsp: T:0, L:4 prot_if GigabitEthernet0/0/0/1 (ifhndl 0x10) , backup_if None (ifhndl 0x0) , protection None Sep 15 02:51:09.774 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 02:51:09.774 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 02:51:15.854 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:6987: frr_event_reroute_added: T:0, L:4, prot_des 0, NH:10.2.5.5 Sep 15 02:51:15.854 mpls_te/bselect 0/RP0/CPU0 t4604 te_frr_add_new_plsp: T:0, L:4 prot_if GigabitEthernet0/0/0/2 (ifhndl 0x18) , backup_if None (ifhndl 0x0) , protection None Sep 15 02:51:15.854 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 02:51:15.854 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 02:51:50.658 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7058: frr_event_resv_arrive: T:0, L:5, backup None (ifh 0x0), NH:10.2.3.3 Sep 15 02:51:50.658 mpls_te/bselect 0/RP0/CPU0 t4604 te_frr_add_new_plsp: T:0, L:5 prot_if GigabitEthernet0/0/0/1 (ifhndl 0x10) , backup_if None (ifhndl 0x0) , protection None Sep 15 02:51:50.658 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 02:51:50.658 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 02:51:54.308 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7058: frr_event_resv_arrive: T:0, L:5, backup None (ifh 0x0), NH:10.1.2.1 Sep 15 02:51:54.308 mpls_te/bselect 0/RP0/CPU0 t4604 te_frr_add_new_plsp: T:0, L:5 prot_if GigabitEthernet0/0/0/0 (ifhndl 0x8) , backup_if None (ifhndl 0x0) , protection None Sep 15 02:51:54.308 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 02:51:54.308 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 02:52:30.929 mpls_te/bselect 0/RP0/CPU0 t4604 frr_event_lsp_down: T:0, L:4, prot_if GigabitEthernet0/0/0/1 (ifhndl 0x10) , NH:10.2.3.3 Sep 15 02:52:34.552 mpls_te/bselect 0/RP0/CPU0 t4604 frr_event_lsp_down: T:0, L:4, prot_if GigabitEthernet0/0/0/2 (ifhndl 0x18) , NH:10.2.5.5 Sep 15 02:54:46.062 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 02:54:46.093 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 02:54:46.123 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:4403: te_frr_promote_intf: All intf processed: promotion complete .... Sep 15 02:59:22.445 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:756: te_frr_set_backup_info: Backup tun T:100 is down Sep 15 02:59:22.656 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:968: te_frr_send_backup_tun: ADD: bkup->id 100, bw_flags 1, bw 0, prot_if GigabitEthernet0/0/0/0 (ifh 0x8) Sep 15 02:59:22.656 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:1191: tebm_add_bkup_tunnel: backup tunnel tunnel-te100 (ifh 0x1c), tail rtr id 1.1.1.1, bw 0, bw_flags 1 Sep 15 02:59:22.656 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:719: Inserted i/f in INTF_NAME thread, prot ifhandle GigabitEthernet0/0/0/0 (ifh 0x8) Sep 15 02:59:22.656 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:740: Inserted i/f in INTF thread, prot ifhandle GigabitEthernet0/0/0/0 (ifh 0x8) Sep 15 02:59:22.656 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:768: Sucessfully initialized prot ifhandle GigabitEthernet0/0/0/0 (ifh 0x8) Sep 15 02:59:22.656 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:5148: frr_event_bkup_up: backup tunnel-te100 (ifh 0x1c) prot_if GigabitEthernet0/0/0/0 (ifh 0x8) Sep 15 02:59:22.656 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 02:59:22.656 mpls_te/bselect 0/RP0/CPU0 t4604 TUN_SIG:3848: te_frr_prepare: backup tunnel tunnel-te100 (ifh 0x1c), tail 1.1.1.1, backup_addr_in_ero 10.1.2.1, RRO MP 1.1.1.1 Sep 15 02:59:46.093 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 03:04:46.063 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 03:06:40.089 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:6428: frr_event_protected_if_nbr_dn: intf GigabitEthernet0/0/0/0 (ifh 0x8), Nbr 0.0.0.0 Sep 15 03:06:42.650 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7058: frr_event_resv_arrive: T:0, L:6, backup None (ifh 0x0), NH:10.2.3.3 Sep 15 03:06:42.650 mpls_te/bselect 0/RP0/CPU0 t4604 te_frr_add_new_plsp: T:0, L:6 prot_if GigabitEthernet0/0/0/1 (ifhndl 0x10) , backup_if None (ifhndl 0x0) , protection None Sep 15 03:06:42.650 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 03:06:42.650 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 03:06:43.445 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:7058: frr_event_resv_arrive: T:0, L:7, backup None (ifh 0x0), NH:10.2.5.5 Sep 15 03:06:43.445 mpls_te/bselect 0/RP0/CPU0 t4604 te_frr_add_new_plsp: T:0, L:7 prot_if GigabitEthernet0/0/0/2 (ifhndl 0x18) , backup_if None (ifhndl 0x0) , protection None Sep 15 03:06:43.445 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 03:06:43.445 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 03:07:22.919 mpls_te/bselect 0/RP0/CPU0 t4604 frr_event_lsp_down: T:0, L:5, prot_if GigabitEthernet0/0/0/1 (ifhndl 0x10) , NH:10.2.3.3 Sep 15 03:07:23.690 mpls_te/bselect 0/RP0/CPU0 t4604 frr_event_lsp_down: T:0, L:5, prot_if GigabitEthernet0/0/0/0 (ifhndl 0x8) , NH:10.1.2.1 Sep 15 03:07:23.690 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:2705: tebm_bkup_tunnel_clear: Backup tun tunnel-te100 (ifh 0x1c), inuse_bw 0, bkup_data->num_lsps 1, frr_bw 0 Sep 15 03:07:23.690 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:3112: te_frr_clear_backup: T:0 freed bw 0 kbps Sep 15 03:07:23.690 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:6308: frr_event_bkup_free_bw: backup tunnel-te100 (ifh 0x1c) prot_if GigabitEthernet0/0/0/0 (ifh 0x8) Sep 15 03:09:46.094 mpls_te/bselect 0/RP0/CPU0 t4604 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... RP/0/RP0/CPU0:P2#show rsvp trace signalling Tue Sep 15 03:13:31.756 UTC 208 wrapping entries (264256 possible, 320 allocated, 0 filtered, 208 total) Sep 15 02:33:59.138 rsvp/sig 0/RP0/CPU0 t4405 SIG:4549: PATH creating network: GigabitEthernet0/0/0/0 (ifh 0x8), obj len: 224, IP src: 11.11.11.11 IP dst: 22.22.22.22, psbs: 0, rsbs: 0 Sep 15 02:33:59.139 rsvp/sig 0/RP0/CPU0 t4405 SIG:721: PATH Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:3) Sep 15 02:33:59.156 rsvp/sig 0/RP0/CPU0 t4405 SIG:2836: PATH outgoing creating: flags:0xc0000008, local_rid:2.2.2.2, nbor:3.3.3.3, nhop:10.2.3.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x10), obj_len: 188 Sep 15 02:33:59.156 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:3) Sep 15 02:33:59.221 rsvp/sig 0/RP0/CPU0 t4405 SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x10), in GigabitEthernet0/0/0/1 (ifh 0x10), obj len: 132, IP src: 10.2.3.3, psbs: 1, rsbs: 0 Sep 15 02:33:59.221 rsvp/sig 0/RP0/CPU0 t4405 SIG:362: RESV Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:3) Sep 15 02:33:59.229 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000008, local/nbor 2.2.2.2/1.1.1.1, obj len: 0x50, wedged 0 Sep 15 02:33:59.229 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x10), current (0 Bps), port 0, src 11.11.11.11 id 3 Sep 15 02:33:59.229 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000008, local/nbor 2.2.2.2/1.1.1.1, obj len: 0x84, wedged 0 Sep 15 02:33:59.230 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:3) Sep 15 02:33:59.230 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (22.22.22.22:0), src (11.11.11.11:3) Sep 15 02:34:06.830 rsvp/sig 0/RP0/CPU0 t4405 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x10), obj len: 236, IP src: 10.2.3.3 IP dst: 10.2.3.2, psbs: 0, rsbs: 0 Sep 15 02:34:06.831 rsvp/sig 0/RP0/CPU0 t4405 SIG:721: PATH Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:3) Sep 15 02:34:06.837 rsvp/sig 0/RP0/CPU0 t4405 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:2.2.2.2, nbor:1.1.1.1, nhop:10.1.2.1, next_ifh:GigabitEthernet0/0/0/0 (ifh 0x8), obj_len: 188 Sep 15 02:34:06.837 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:3) Sep 15 02:34:06.874 rsvp/sig 0/RP0/CPU0 t4405 SIG:4654: RESV creating network: GigabitEthernet0/0/0/0 (ifh 0x8), in GigabitEthernet0/0/0/0 (ifh 0x8), obj len: 132, IP src: 10.1.2.1, psbs: 1, rsbs: 0 Sep 15 02:34:06.874 rsvp/sig 0/RP0/CPU0 t4405 SIG:362: RESV Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:3) Sep 15 02:34:06.881 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 2.2.2.2/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 02:34:06.881 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x8), current (0 Bps), port 0, src 22.22.22.22 id 3 Sep 15 02:34:06.881 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 2.2.2.2/3.3.3.3, obj len: 0x84, wedged 0 Sep 15 02:34:06.881 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:3) Sep 15 02:34:06.881 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (11.11.11.11:0), src (22.22.22.22:3) Sep 15 02:34:41.890 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 2.2.2.2/1.1.1.1, obj len: 0x84, wedged 0 Sep 15 02:34:41.890 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:3) Sep 15 02:34:41.890 rsvp/sig 0/RP0/CPU0 t4405 SIG:804: PATH Tunnel IPv4 changed: dst (22.22.22.22:0), src (11.11.11.11:3) Sep 15 02:42:03.258 rsvp/sig 0/RP0/CPU0 t4405 SIG:3929: PATH destroy: dst (22.22.22.22:0), src (11.11.11.11:3) reason: (13): Link or nbor went down (no protection) Sep 15 02:42:03.258 rsvp/sig 0/RP0/CPU0 t4405 SIG:3931: PATH destroy: flags 0x20000, psb flags 0xc0200018, pfc flags 0x80180009 Sep 15 02:42:03.258 rsvp/sig 0/RP0/CPU0 t4405 SIG:1214: RESV destroy: dst (22.22.22.22:0), src (11.11.11.11:3) reason: (13): Link or nbor went down (no protection) Sep 15 02:42:03.258 rsvp/sig 0/RP0/CPU0 t4405 SIG:1216: RESV destroy: flags 0x180000, rsb flags 0xc0000020, request flags 0x86801844 Sep 15 02:42:03.259 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x10), current (0 Bps), port 0, src 11.11.11.11 id 3 Sep 15 02:42:03.259 rsvp/sig 0/RP0/CPU0 t4405 SIG:9276: PathErr: from local for dst (22.22.22.22:0), src (11.11.11.11:3); PSB flags: 0xc0600018 Sep 15 02:42:03.259 rsvp/sig 0/RP0/CPU0 t4405 SIG:9279: PathErr: (24, 5)-(Error: routing (24), Suberror: no route to dest (5)) at 10.2.3.2; flags: 0x4 Sep 15 02:42:03.259 rsvp/sig 0/RP0/CPU0 t4405 SIG:1214: RESV destroy: dst (11.11.11.11:0), src (22.22.22.22:3) reason: (13): Link or nbor went down (no protection) Sep 15 02:42:03.259 rsvp/sig 0/RP0/CPU0 t4405 SIG:1216: RESV destroy: flags 0x20000, rsb flags 0xc0080020, request flags 0x86001844 Sep 15 02:42:03.260 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x8), current (0 Bps), port 0, src 22.22.22.22 id 3 Sep 15 02:42:03.260 rsvp/sig 0/RP0/CPU0 t4405 SIG:9276: PathErr: from local for dst (11.11.11.11:0), src (22.22.22.22:3); PSB flags: 0xc0600018 Sep 15 02:42:03.260 rsvp/sig 0/RP0/CPU0 t4405 SIG:9279: PathErr: (24, 5)-(Error: routing (24), Suberror: no route to dest (5)) at 10.1.2.2; flags: 0x4 Sep 15 02:42:03.260 rsvp/sig 0/RP0/CPU0 t4405 SIG:3929: PATH destroy: dst (11.11.11.11:0), src (22.22.22.22:3) reason: (13): Link or nbor went down (no protection) Sep 15 02:42:03.260 rsvp/sig 0/RP0/CPU0 t4405 SIG:3931: PATH destroy: flags 0x20000, psb flags 0xc0200018, pfc flags 0x80180009 Sep 15 02:42:03.349 rsvp/sig 0/RP0/CPU0 t4405 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x10), obj len: 252, IP src: 3.3.3.3 IP dst: 10.2.3.2, psbs: 0, rsbs: 0 Sep 15 02:42:03.349 rsvp/sig 0/RP0/CPU0 t4405 SIG:721: PATH Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:42:03.349 rsvp/sig 0/RP0/CPU0 t4405 SIG:4549: PATH creating network: GigabitEthernet0/0/0/2 (ifh 0x18), obj len: 240, IP src: 11.11.11.11 IP dst: 22.22.22.22, psbs: 0, rsbs: 0 Sep 15 02:42:03.349 rsvp/sig 0/RP0/CPU0 t4405 SIG:721: PATH Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:42:03.354 rsvp/sig 0/RP0/CPU0 t4405 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:2.2.2.2, nbor:5.5.5.5, nhop:10.2.5.5, next_ifh:GigabitEthernet0/0/0/2 (ifh 0x18), obj_len: 204 Sep 15 02:42:03.354 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:42:03.355 rsvp/sig 0/RP0/CPU0 t4405 SIG:2836: PATH outgoing creating: flags:0xc0000008, local_rid:2.2.2.2, nbor:3.3.3.3, nhop:10.2.3.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x10), obj_len: 188 Sep 15 02:42:03.355 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:42:03.447 rsvp/sig 0/RP0/CPU0 t4405 SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x10), in GigabitEthernet0/0/0/1 (ifh 0x10), obj len: 180, IP src: 10.2.3.3, psbs: 1, rsbs: 0 Sep 15 02:42:03.447 rsvp/sig 0/RP0/CPU0 t4405 SIG:362: RESV Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:42:03.455 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000008, local/nbor 2.2.2.2/5.5.5.5, obj len: 0x50, wedged 0 Sep 15 02:42:03.455 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x10), current (0 Bps), port 0, src 11.11.11.11 id 4 Sep 15 02:42:03.455 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000008, local/nbor 2.2.2.2/5.5.5.5, obj len: 0xb4, wedged 0 Sep 15 02:42:03.456 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:42:03.456 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:42:03.571 rsvp/sig 0/RP0/CPU0 t4405 SIG:4654: RESV creating network: GigabitEthernet0/0/0/2 (ifh 0x18), in GigabitEthernet0/0/0/2 (ifh 0x18), obj len: 244, IP src: 10.2.5.5, psbs: 1, rsbs: 0 Sep 15 02:42:03.571 rsvp/sig 0/RP0/CPU0 t4405 SIG:362: RESV Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:42:03.579 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 2.2.2.2/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 02:42:03.579 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/2 (ifh 0x18), current (0 Bps), port 0, src 22.22.22.22 id 4 Sep 15 02:42:03.579 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 2.2.2.2/3.3.3.3, obj len: 0xf4, wedged 0 Sep 15 02:42:03.579 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:42:03.579 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:42:48.883 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 2.2.2.2/5.5.5.5, obj len: 0xb4, wedged 0 Sep 15 02:42:48.883 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:42:48.883 rsvp/sig 0/RP0/CPU0 t4405 SIG:804: PATH Tunnel IPv4 changed: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:51:09.770 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000218, local/nbor 2.2.2.2/5.5.5.5, obj len: 0xb4, wedged 0 Sep 15 02:51:09.770 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:51:09.770 rsvp/sig 0/RP0/CPU0 t4405 SIG:804: PATH Tunnel IPv4 changed: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:51:09.776 rsvp/sig 0/RP0/CPU0 t4405 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:2.2.2.2, nbor:3.3.3.3, nhop:10.2.3.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x10), obj_len: 188 Sep 15 02:51:09.776 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:51:09.776 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000098, local/nbor 2.2.2.2/5.5.5.5, obj len: 0xb4, wedged 0 Sep 15 02:51:09.776 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:51:09.776 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:51:15.850 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000218, local/nbor 2.2.2.2/3.3.3.3, obj len: 0xf4, wedged 0 Sep 15 02:51:15.850 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:51:15.850 rsvp/sig 0/RP0/CPU0 t4405 SIG:804: PATH Tunnel IPv4 changed: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:51:15.856 rsvp/sig 0/RP0/CPU0 t4405 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:2.2.2.2, nbor:5.5.5.5, nhop:10.2.5.5, next_ifh:GigabitEthernet0/0/0/2 (ifh 0x18), obj_len: 204 Sep 15 02:51:15.856 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:51:15.856 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000098, local/nbor 2.2.2.2/3.3.3.3, obj len: 0xf4, wedged 0 Sep 15 02:51:15.856 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:51:15.856 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:51:28.959 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/2 (ifh 0x18), current (0 Bps), port 0, src 22.22.22.22 id 4 Sep 15 02:51:28.959 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000120, psb flags: 0xc0000018, local/nbor 2.2.2.2/3.3.3.3, obj len: 0xf4, wedged 0 Sep 15 02:51:28.959 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:51:28.959 rsvp/sig 0/RP0/CPU0 t4405 SIG:445: RESV Tunnel IPv4 changed: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:51:36.306 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/2 (ifh 0x18), current (0 Bps), port 0, src 22.22.22.22 id 4 Sep 15 02:51:36.306 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000120, psb flags: 0xc0000018, local/nbor 2.2.2.2/3.3.3.3, obj len: 0xf4, wedged 0 Sep 15 02:51:36.306 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:51:36.306 rsvp/sig 0/RP0/CPU0 t4405 SIG:445: RESV Tunnel IPv4 changed: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 02:51:43.619 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x10), current (0 Bps), port 0, src 11.11.11.11 id 4 Sep 15 02:51:43.619 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000120, psb flags: 0xc0000018, local/nbor 2.2.2.2/5.5.5.5, obj len: 0xb4, wedged 0 Sep 15 02:51:43.619 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:51:43.619 rsvp/sig 0/RP0/CPU0 t4405 SIG:445: RESV Tunnel IPv4 changed: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 02:51:50.611 rsvp/sig 0/RP0/CPU0 t4405 SIG:4549: PATH creating network: GigabitEthernet0/0/0/0 (ifh 0x8), obj len: 224, IP src: 11.11.11.11 IP dst: 22.22.22.22, psbs: 1, rsbs: 1 Sep 15 02:51:50.612 rsvp/sig 0/RP0/CPU0 t4405 SIG:721: PATH Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 02:51:50.617 rsvp/sig 0/RP0/CPU0 t4405 SIG:2836: PATH outgoing creating: flags:0xc0000008, local_rid:2.2.2.2, nbor:3.3.3.3, nhop:10.2.3.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x10), obj_len: 188 Sep 15 02:51:50.617 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 02:51:50.652 rsvp/sig 0/RP0/CPU0 t4405 SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x10), in GigabitEthernet0/0/0/1 (ifh 0x10), obj len: 180, IP src: 10.2.3.3, psbs: 2, rsbs: 1 Sep 15 02:51:50.652 rsvp/sig 0/RP0/CPU0 t4405 SIG:362: RESV Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 02:51:50.661 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000008, local/nbor 2.2.2.2/1.1.1.1, obj len: 0x50, wedged 0 Sep 15 02:51:50.661 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x10), current (0 Bps), port 0, src 11.11.11.11 id 5 Sep 15 02:51:50.661 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000008, local/nbor 2.2.2.2/1.1.1.1, obj len: 0xb4, wedged 0 Sep 15 02:51:50.662 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 02:51:50.662 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 02:51:54.258 rsvp/sig 0/RP0/CPU0 t4405 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x10), obj len: 236, IP src: 10.2.3.3 IP dst: 10.2.3.2, psbs: 1, rsbs: 1 Sep 15 02:51:54.258 rsvp/sig 0/RP0/CPU0 t4405 SIG:721: PATH Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:5) Sep 15 02:51:54.265 rsvp/sig 0/RP0/CPU0 t4405 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:2.2.2.2, nbor:1.1.1.1, nhop:10.1.2.1, next_ifh:GigabitEthernet0/0/0/0 (ifh 0x8), obj_len: 188 Sep 15 02:51:54.265 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:5) Sep 15 02:51:54.303 rsvp/sig 0/RP0/CPU0 t4405 SIG:4654: RESV creating network: GigabitEthernet0/0/0/0 (ifh 0x8), in GigabitEthernet0/0/0/0 (ifh 0x8), obj len: 180, IP src: 10.1.2.1, psbs: 2, rsbs: 1 Sep 15 02:51:54.304 rsvp/sig 0/RP0/CPU0 t4405 SIG:362: RESV Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:5) Sep 15 02:51:54.312 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 2.2.2.2/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 02:51:54.312 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x8), current (0 Bps), port 0, src 22.22.22.22 id 5 Sep 15 02:51:54.312 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 2.2.2.2/3.3.3.3, obj len: 0xb4, wedged 0 Sep 15 02:51:54.312 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:5) Sep 15 02:51:54.312 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (11.11.11.11:0), src (22.22.22.22:5) Sep 15 02:52:30.925 rsvp/sig 0/RP0/CPU0 t4405 SIG:3929: PATH destroy: dst (22.22.22.22:0), src (11.11.11.11:4) reason: (2): State deleted due to signaling Sep 15 02:52:30.925 rsvp/sig 0/RP0/CPU0 t4405 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 02:52:30.925 rsvp/sig 0/RP0/CPU0 t4405 SIG:1214: RESV destroy: dst (22.22.22.22:0), src (11.11.11.11:4) reason: (2): State deleted due to signaling Sep 15 02:52:30.925 rsvp/sig 0/RP0/CPU0 t4405 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0000020, request flags 0x86003844 Sep 15 02:52:30.925 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x10), current (0 Bps), port 0, src 11.11.11.11 id 4 Sep 15 02:52:33.708 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 2.2.2.2/1.1.1.1, obj len: 0xb4, wedged 0 Sep 15 02:52:33.708 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 02:52:33.708 rsvp/sig 0/RP0/CPU0 t4405 SIG:804: PATH Tunnel IPv4 changed: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 02:52:34.548 rsvp/sig 0/RP0/CPU0 t4405 SIG:3929: PATH destroy: dst (11.11.11.11:0), src (22.22.22.22:4) reason: (2): State deleted due to signaling Sep 15 02:52:34.548 rsvp/sig 0/RP0/CPU0 t4405 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 02:52:34.548 rsvp/sig 0/RP0/CPU0 t4405 SIG:1214: RESV destroy: dst (11.11.11.11:0), src (22.22.22.22:4) reason: (2): State deleted due to signaling Sep 15 02:52:34.548 rsvp/sig 0/RP0/CPU0 t4405 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0000020, request flags 0x86003844 Sep 15 02:52:34.549 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/2 (ifh 0x18), current (0 Bps), port 0, src 22.22.22.22 id 4 Sep 15 02:59:15.765 rsvp/sig 0/RP0/CPU0 t4405 SIG:4549: PATH creating network: GigabitEthernet0/0/0/2 (ifh 0x18), obj len: 200, IP src: 10.2.5.5 IP dst: 10.2.5.2, psbs: 0, rsbs: 0 Sep 15 02:59:15.766 rsvp/sig 0/RP0/CPU0 t4405 SIG:721: PATH Tunnel IPv4 created: dst (2.2.2.2:100), src (1.1.1.1:2) Sep 15 02:59:15.772 rsvp/sig 0/RP0/CPU0 t4405 SIG:4649: RESV creating tail: local/nbor (2.2.2.2/5.5.5.5), obj len: 80, None (ifh 0x0), psbs: 1, rsbs: 0 Sep 15 02:59:15.772 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000005, psb flags: 0xc0000018, local/nbor 2.2.2.2/5.5.5.5, obj len: 0x50, wedged 0 Sep 15 02:59:15.772 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (2.2.2.2:100), src (1.1.1.1:2) Sep 15 02:59:15.772 rsvp/sig 0/RP0/CPU0 t4405 SIG:362: RESV Tunnel IPv4 created: dst (2.2.2.2:100), src (1.1.1.1:2) Sep 15 02:59:22.591 rsvp/sig 0/RP0/CPU0 t4405 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 0x18), psbs: 0, rsbs: 0 Sep 15 02:59:22.591 rsvp/sig 0/RP0/CPU0 t4405 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 0x18), obj_len: 0 Sep 15 02:59:22.591 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (1.1.1.1:100), src (2.2.2.2:2) Sep 15 02:59:22.592 rsvp/sig 0/RP0/CPU0 t4405 SIG:721: PATH Tunnel IPv4 created: dst (1.1.1.1:100), src (2.2.2.2:2) Sep 15 02:59:22.650 rsvp/sig 0/RP0/CPU0 t4405 SIG:4654: RESV creating network: GigabitEthernet0/0/0/2 (ifh 0x18), in GigabitEthernet0/0/0/2 (ifh 0x18), obj len: 112, IP src: 10.2.5.5, psbs: 1, rsbs: 0 Sep 15 02:59:22.650 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/2 (ifh 0x18), current (0 Bps), port 100, src 2.2.2.2 id 2 Sep 15 02:59:22.650 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000011, psb flags: 0xc0000004, local/nbor 2.2.2.2/0.0.0.0, obj len: 0x70, wedged 0 Sep 15 02:59:22.650 rsvp/sig 0/RP0/CPU0 t4405 SIG:362: RESV Tunnel IPv4 created: dst (1.1.1.1:100), src (2.2.2.2:2) Sep 15 02:59:22.659 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (1.1.1.1:100), src (2.2.2.2:2) Sep 15 02:59:22.659 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 2.2.2.2/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 02:59:22.665 rsvp/sig 0/RP0/CPU0 t4405 SIG:414: Assigned backup tunnel-te100 (ifh 0x1c): dst (11.11.11.11:0), src (22.22.22.22:5) Sep 15 03:06:40.089 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x8), current (0 Bps), port 0, src 22.22.22.22 id 5 Sep 15 03:06:40.089 rsvp/sig 0/RP0/CPU0 t4405 SIG:8622: Fast-rerouting path state: dst (11.11.11.11:0), src (22.22.22.22:5), going over tunnel-te100 (ifh 0x1c) to merge point 1.1.1.1 Sep 15 03:06:40.089 rsvp/sig 0/RP0/CPU0 t4405 SIG:9276: PathErr: from local for dst (11.11.11.11:0), src (22.22.22.22:5); PSB flags: 0xc0000018 Sep 15 03:06:40.089 rsvp/sig 0/RP0/CPU0 t4405 SIG:9279: PathErr: (25, 3)-(Error: notify (25), Suberror: local repair (3)) at 10.2.3.2; flags: 0x0 Sep 15 03:06:40.149 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc0001040, psb flags: 0xc0000038, local/nbor 2.2.2.2/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 03:06:40.149 rsvp/sig 0/RP0/CPU0 t4405 SIG:406: Changed backup from tunnel-te100 (ifh 0x1c) to tunnel-te100 (ifh 0x1c): dst (11.11.11.11:0), src (22.22.22.22:5) Sep 15 03:06:40.151 rsvp/sig 0/RP0/CPU0 t4405 SIG:4549: PATH creating network: GigabitEthernet0/0/0/2 (ifh 0x18), obj len: 252, IP src: 10.2.5.5 IP dst: 10.2.5.2, psbs: 1, rsbs: 1 Sep 15 03:06:40.151 rsvp/sig 0/RP0/CPU0 t4405 SIG:721: PATH Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:6) Sep 15 03:06:40.158 rsvp/sig 0/RP0/CPU0 t4405 SIG:9276: PathErr: from client for dst (11.11.11.11:0), src (22.22.22.22:6); PSB flags: 0xc0008018 Sep 15 03:06:40.158 rsvp/sig 0/RP0/CPU0 t4405 SIG:9279: PathErr: (24, 5)-(Error: routing (24), Suberror: no route to dest (5)) at 10.2.5.2; flags: 0x4 Sep 15 03:06:40.158 rsvp/sig 0/RP0/CPU0 t4405 SIG:3929: PATH destroy: dst (11.11.11.11:0), src (22.22.22.22:6) reason: (15): State deleted due to PERR w/ PSR from client app Sep 15 03:06:40.158 rsvp/sig 0/RP0/CPU0 t4405 SIG:3931: PATH destroy: flags 0x40000, psb flags 0xc0008018, pfc flags 0x0 Sep 15 03:06:42.516 rsvp/sig 0/RP0/CPU0 t4405 SIG:4549: PATH creating network: GigabitEthernet0/0/0/2 (ifh 0x18), obj len: 252, IP src: 10.2.5.5 IP dst: 10.2.5.2, psbs: 1, rsbs: 1 Sep 15 03:06:42.516 rsvp/sig 0/RP0/CPU0 t4405 SIG:721: PATH Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:6) Sep 15 03:06:42.549 rsvp/sig 0/RP0/CPU0 t4405 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:2.2.2.2, nbor:3.3.3.3, nhop:10.2.3.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x10), obj_len: 188 Sep 15 03:06:42.549 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:6) Sep 15 03:06:42.646 rsvp/sig 0/RP0/CPU0 t4405 SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x10), in GigabitEthernet0/0/0/1 (ifh 0x10), obj len: 180, IP src: 10.2.3.3, psbs: 2, rsbs: 1 Sep 15 03:06:42.646 rsvp/sig 0/RP0/CPU0 t4405 SIG:362: RESV Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:6) Sep 15 03:06:42.654 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 2.2.2.2/5.5.5.5, obj len: 0x50, wedged 0 Sep 15 03:06:42.654 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x10), current (0 Bps), port 0, src 11.11.11.11 id 6 Sep 15 03:06:42.654 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 2.2.2.2/5.5.5.5, obj len: 0xb4, wedged 0 Sep 15 03:06:42.654 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:6) Sep 15 03:06:42.654 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (22.22.22.22:0), src (11.11.11.11:6) Sep 15 03:06:43.285 rsvp/sig 0/RP0/CPU0 t4405 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x10), obj len: 252, IP src: 10.2.3.3 IP dst: 10.2.3.2, psbs: 1, rsbs: 1 Sep 15 03:06:43.285 rsvp/sig 0/RP0/CPU0 t4405 SIG:721: PATH Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:7) Sep 15 03:06:43.291 rsvp/sig 0/RP0/CPU0 t4405 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:2.2.2.2, nbor:5.5.5.5, nhop:10.2.5.5, next_ifh:GigabitEthernet0/0/0/2 (ifh 0x18), obj_len: 204 Sep 15 03:06:43.291 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:7) Sep 15 03:06:43.424 rsvp/sig 0/RP0/CPU0 t4405 SIG:4654: RESV creating network: GigabitEthernet0/0/0/2 (ifh 0x18), in GigabitEthernet0/0/0/2 (ifh 0x18), obj len: 244, IP src: 10.2.5.5, psbs: 2, rsbs: 1 Sep 15 03:06:43.424 rsvp/sig 0/RP0/CPU0 t4405 SIG:362: RESV Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:7) Sep 15 03:06:43.449 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 2.2.2.2/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 03:06:43.449 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/2 (ifh 0x18), current (0 Bps), port 0, src 22.22.22.22 id 7 Sep 15 03:06:43.449 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 2.2.2.2/3.3.3.3, obj len: 0xf4, wedged 0 Sep 15 03:06:43.449 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:7) Sep 15 03:06:43.449 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (11.11.11.11:0), src (22.22.22.22:7) Sep 15 03:06:46.347 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc00001a0, psb flags: 0xc004030a, local/nbor 2.2.2.2/0.0.0.0, obj len: 0xb4, wedged 0 Sep 15 03:06:46.347 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 03:06:46.347 rsvp/sig 0/RP0/CPU0 t4405 SIG:804: PATH Tunnel IPv4 changed: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 03:06:46.353 rsvp/sig 0/RP0/CPU0 t4405 SIG:2836: PATH outgoing creating: flags:0xc000010a, local_rid:2.2.2.2, nbor:3.3.3.3, nhop:10.2.3.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x10), obj_len: 188 Sep 15 03:06:46.353 rsvp/sig 0/RP0/CPU0 t4405 SIG:887: PATH Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 03:06:46.353 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc00001a0, psb flags: 0xc000018a, local/nbor 2.2.2.2/0.0.0.0, obj len: 0xb4, wedged 0 Sep 15 03:06:46.353 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 03:06:46.354 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc00001a0, psb flags: 0xc000010a, local/nbor 2.2.2.2/1.1.1.1, obj len: 0x50, wedged 0 Sep 15 03:06:46.354 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x10), current (0 Bps), port 0, src 11.11.11.11 id 5 Sep 15 03:06:46.354 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xd00001a0, psb flags: 0xc000010a, local/nbor 2.2.2.2/1.1.1.1, obj len: 0xb4, wedged 0 Sep 15 03:06:46.354 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 03:06:46.354 rsvp/sig 0/RP0/CPU0 t4405 SIG:492: Assigned backup None (ifh 0x0): dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 03:06:47.846 rsvp/sig 0/RP0/CPU0 t4405 SIG:445: RESV Tunnel IPv4 changed: dst (11.11.11.11:0), src (22.22.22.22:5) Sep 15 03:06:47.852 rsvp/sig 0/RP0/CPU0 t4405 SIG:4130: RESV outgoing creating: rsb flags: 0xc0241060, psb flags: 0xc0080038, local/nbor 2.2.2.2/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 03:06:47.852 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xd0041160, psb flags: 0xc0080038, local/nbor 2.2.2.2/3.3.3.3, obj len: 0xb4, wedged 0 Sep 15 03:06:47.852 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:5) Sep 15 03:06:47.852 rsvp/sig 0/RP0/CPU0 t4405 SIG:406: Changed backup from tunnel-te100 (ifh 0x1c) to tunnel-te100 (ifh 0x1c): dst (11.11.11.11:0), src (22.22.22.22:5) Sep 15 03:06:50.833 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x10), current (0 Bps), port 0, src 11.11.11.11 id 5 Sep 15 03:06:50.833 rsvp/sig 0/RP0/CPU0 t4405 SIG:2881: RESV outgoing creating: rsb flags: 0xc00001a0, psb flags: 0xc000010a, local/nbor 2.2.2.2/1.1.1.1, obj len: 0xb4, wedged 0 Sep 15 03:06:50.833 rsvp/sig 0/RP0/CPU0 t4405 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 03:06:50.833 rsvp/sig 0/RP0/CPU0 t4405 SIG:445: RESV Tunnel IPv4 changed: dst (22.22.22.22:0), src (11.11.11.11:5) Sep 15 03:07:22.916 rsvp/sig 0/RP0/CPU0 t4405 SIG:3929: PATH destroy: dst (22.22.22.22:0), src (11.11.11.11:5) reason: (2): State deleted due to signaling Sep 15 03:07:22.916 rsvp/sig 0/RP0/CPU0 t4405 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc000010a, pfc flags 0x80180009 Sep 15 03:07:22.916 rsvp/sig 0/RP0/CPU0 t4405 SIG:1214: RESV destroy: dst (22.22.22.22:0), src (11.11.11.11:5) reason: (2): State deleted due to signaling Sep 15 03:07:22.916 rsvp/sig 0/RP0/CPU0 t4405 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc00000a0, request flags 0x86203844 Sep 15 03:07:22.916 rsvp/sig 0/RP0/CPU0 t4405 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x10), current (0 Bps), port 0, src 11.11.11.11 id 5 Sep 15 03:07:23.686 rsvp/sig 0/RP0/CPU0 t4405 SIG:3929: PATH destroy: dst (11.11.11.11:0), src (22.22.22.22:5) reason: (2): State deleted due to signaling Sep 15 03:07:23.686 rsvp/sig 0/RP0/CPU0 t4405 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0080038, pfc flags 0x80004001 Sep 15 03:07:23.686 rsvp/sig 0/RP0/CPU0 t4405 SIG:1214: RESV destroy: dst (11.11.11.11:0), src (22.22.22.22:5) reason: (2): State deleted due to signaling Sep 15 03:07:23.686 rsvp/sig 0/RP0/CPU0 t4405 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0041060, request flags 0x86003844