RP/0/RP0/CPU0:P6#show mpls traffic-eng trace head-end Tue Sep 15 04:47:20.152 UTC 4 wrapping entries (264256 possible, 64 allocated, 0 filtered, 4 total) Sep 15 02:28:31.509 mpls_te/head-end 0/RP0/CPU0 t4581 [PCALC-ECMP] Max BWV Allocated: 20 Sep 15 02:28:31.509 mpls_te/head-end 0/RP0/CPU0 t4581 [PCALC-ECMP] Max BWVE Allocated: 15 Sep 15 02:28:33.494 mpls_te/head-end 0/RP0/CPU0 t4581 NOTFN OwnedResEnd Sep 15 02:28:34.793 mpls_te/head-end 0/RP0/CPU0 t4581 Validating ifindexes for all vifs RP/0/RP0/CPU0:P6#show mpls traffic-eng trace link Tue Sep 15 04:47:20.287 UTC 195 wrapping entries (67648 possible, 320 allocated, 0 filtered, 195 total) Sep 15 02:28:31.509 mpls_te/link 0/RP0/CPU0 t4581 DS-TE mode change: prev - 0, new - 1 Sep 15 02:28:31.509 mpls_te/link 0/RP0/CPU0 t4581 TE process restarting; aborting DS-TE mode change Sep 15 02:28:32.792 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:381: lm_iarm_control_cb_fn: connection to IARM established. Sep 15 02:28:33.621 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry Sep 15 02:28:33.621 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/0 (ifh 0x10) into db based on name and handle Sep 15 02:28:33.621 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x10), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:33.621 mpls_te/link 0/RP0/CPU0 t4581 RSI: Registering interface GigabitEthernet0/0/0/0 (ifhndl 0x10) (0x10) for SRLG Notification Sep 15 02:28:33.621 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/0 (ifh 0x10) Created [1 links total], enable 0 Sep 15 02:28:33.623 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/0 (ifh 0x10) BW attribute 0 kbps Sep 15 02:28:33.623 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/0 (ifh 0x10) physical_bw 125000000 Bps Sep 15 02:28:33.626 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:33.626 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:33.626 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/0 (ifh 0x10), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:33.626 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/0 (ifh 0x10), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:33.626 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/0 (ifh 0x10), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:33.626 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/0 (ifh 0x10), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:33.626 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/0 (ifh 0x10) state down Sep 15 02:28:33.630 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/0 (ifh 0x10) to IARM batch Sep 15 02:28:33.630 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:756: lm_iarm_reg_ipv4_add: sent pulse (0) to flush IARM IPv4 registration batch. Sep 15 02:28:33.631 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry Sep 15 02:28:33.631 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/1 (ifh 0x18) into db based on name and handle Sep 15 02:28:33.631 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x18), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:33.631 mpls_te/link 0/RP0/CPU0 t4581 RSI: Registering interface GigabitEthernet0/0/0/1 (ifhndl 0x18) (0x18) for SRLG Notification Sep 15 02:28:33.631 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/1 (ifh 0x18) Created [2 links total], enable 0 Sep 15 02:28:33.631 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) BW attribute 0 kbps Sep 15 02:28:33.631 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) physical_bw 125000000 Bps Sep 15 02:28:33.635 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:33.635 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:33.635 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x18), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:33.635 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x18), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:33.635 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x18), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:33.635 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x18), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 15 02:28:33.635 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/1 (ifh 0x18) state down Sep 15 02:28:33.638 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/1 (ifh 0x18) to IARM batch Sep 15 02:28:33.639 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x10), state 17, proto 0, opcode 35 (CREATE/DEL) Sep 15 02:28:33.639 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x10), intf_type 15, parent i/f None (ifh 0x0), caps 0 Sep 15 02:28:33.639 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/0 (ifh 0x10), state 17, proto 0, opcode 35 Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/0 (ifh 0x10), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/0 (ifh 0x10) BW attribute 0 kbps Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/0 (ifh 0x10) physical_bw 125000000 Bps Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x10) suppressed, system not ready Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), state 17, proto 0, opcode 35 (CREATE/DEL) Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), intf_type 15, parent i/f None (ifh 0x0), caps 0 Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/1 (ifh 0x18), state 17, proto 0, opcode 35 Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/1 (ifh 0x18), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) BW attribute 0 kbps Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) physical_bw 125000000 Bps Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:33.640 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x18) suppressed, system not ready Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 RSI: Synchronous batch handler: 2 items Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 RSI: Event Update with 0 SRLG values for GigabitEthernet0/0/0/0 (ifhndl 0x10) (0x10) [change: NO, old count 0] Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 RSI: Event Update with 0 SRLG values for GigabitEthernet0/0/0/1 (ifhndl 0x18) (0x18) [change: NO, old count 0] Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 RSI: Sent registration for 2 interfaces (Success 2, failure 0) Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_im_attr_capacity_handler: link MgmtEth0/RP0/CPU0/0 (ifh 0x8),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/0 (ifh 0x10),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_process_link_capacity(): capacity not changed link GigabitEthernet0/0/0/0 (ifh 0x10), current bw 125000000 (Kbps), new bw 125000000 (Kbps) Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/1 (ifh 0x18),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_process_link_capacity(): capacity not changed link GigabitEthernet0/0/0/1 (ifh 0x18), current bw 125000000 (Kbps), new bw 125000000 (Kbps) Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/2 (ifh 0x20),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/3 (ifh 0x28),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 15 02:28:33.641 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:279: lm_iarm_handle_pulse: received pulse (0), flushing IARM IPv4 registration batch. Sep 15 02:28:33.685 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:623: lm_iarm_flush: sent 2 IPv4 (un)register requests to IARM. Sep 15 02:28:33.909 mpls_te/link 0/RP0/CPU0 t4581 SRLG-producer connected Sep 15 02:28:33.911 mpls_te/link 0/RP0/CPU0 t4581 RSI SRLG-producer registration done successfully Sep 15 02:28:33.911 mpls_te/link 0/RP0/CPU0 t4581 Replaying learned SRLGs on all links to RSI Sep 15 02:28:33.911 mpls_te/link 0/RP0/CPU0 t4581 Replaying learned SRLGs on all termination interfaces to RSI Sep 15 02:28:34.621 mpls_te/link 0/RP0/CPU0 t4581 Validating ifindexes for all links Sep 15 02:28:34.621 mpls_te/link 0/RP0/CPU0 t4581 Bulk ifindex lookup for 2 links Sep 15 02:28:34.624 mpls_te/link 0/RP0/CPU0 t4581 Link GigabitEthernet0/0/0/0 (ifhndl 0x10) (0x10) ifindex set to 3 Sep 15 02:28:34.624 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x10), flood 0, force 1, Nbr cnt 0 Sep 15 02:28:34.625 mpls_te/link 0/RP0/CPU0 t4581 Link GigabitEthernet0/0/0/1 (ifhndl 0x18) (0x18) ifindex set to 4 Sep 15 02:28:34.625 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x18), flood 0, force 1, Nbr cnt 0 Sep 15 02:28:34.791 mpls_te/link 0/RP0/CPU0 t4581 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:34.791 mpls_te/link 0/RP0/CPU0 t4581 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:35.815 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2611: Handling area change: IGP OSPF area 0, is_up = 1, router-id 6.6.6.6 Sep 15 02:28:35.815 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x10), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:35.815 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x18), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:35.815 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5406: Periodic Flooding for igp-type: 2 area: 0 0 seconds Sep 15 02:28:35.815 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5214: System change: flooding to all links/areas: reason area state change Sep 15 02:28:38.303 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x10), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 15 02:28:38.303 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x10), intf_type 15, parent i/f None (ifh 0x0), caps 26 Sep 15 02:28:38.304 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/0 (ifh 0x10), state 17, proto 12, opcode 35 Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/0 (ifh 0x10), add True Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/0 (ifh 0x10) to IARM batch Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:756: lm_iarm_reg_ipv4_add: sent pulse (0) to flush IARM IPv4 registration batch. Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/0 (ifh 0x10), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/0 (ifh 0x10) BW attribute 0 kbps Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/0 (ifh 0x10) physical_bw 125000000 Bps Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/0 (ifh 0x10) pool1_bw 0 (Kbps) Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x10) suppressed, no change Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), intf_type 15, parent i/f None (ifh 0x0), caps 26 Sep 15 02:28:38.305 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/1 (ifh 0x18), state 17, proto 12, opcode 35 Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/1 (ifh 0x18), add True Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/1 (ifh 0x18) to IARM batch Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/1 (ifh 0x18), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) BW attribute 0 kbps Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x18) physical_bw 125000000 Bps Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x18) pool1_bw 0 (Kbps) Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x18) suppressed, no change Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x10), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x10), state 1, proto 12, opcode 37 Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:38.386 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x18), state 1, proto 12, opcode 37 Sep 15 02:28:38.387 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:279: lm_iarm_handle_pulse: received pulse (0), flushing IARM IPv4 registration batch. Sep 15 02:28:38.387 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:623: lm_iarm_flush: sent 2 IPv4 (un)register requests to IARM. Sep 15 02:28:38.400 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/0 (ifh 0x10), ct=0x0, ps=0x0, addr 10.5.6.6 Sep 15 02:28:38.400 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/1 (ifh 0x18), ct=0x0, ps=0x0, addr 10.3.6.6 Sep 15 02:28:38.790 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x10), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:38.790 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x10), state 2, proto 12, opcode 37 Sep 15 02:28:38.790 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:38.790 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x18), state 2, proto 12, opcode 37 Sep 15 02:28:38.995 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/0 (ifh 0x10), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:38.995 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/0 (ifh 0x10), state 3, proto 12, opcode 37 Sep 15 02:28:38.995 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/0 (ifh 0x10), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:38.996 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/0 (ifh 0x10), area 0, IGP OSPF Sep 15 02:28:39.086 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x18), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 15 02:28:39.086 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x18), state 3, proto 12, opcode 37 Sep 15 02:28:39.086 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x18), flood 0, force 0, Nbr cnt 0 Sep 15 02:28:39.086 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/1 (ifh 0x18), area 0, IGP OSPF Sep 15 02:28:39.092 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x10) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:39.092 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/0 (ifh 0x10), tot_nbrs 0, subnet type 0, flags 0x2 Sep 15 02:28:39.092 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x10) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:39.092 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:935: Link GigabitEthernet0/0/0/0 (ifh 0x10): Added area 0 IGP OSPF Sep 15 02:28:39.092 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1019: Link GigabitEthernet0/0/0/0 (ifh 0x10): Update nbrs for area 0 IGP OSPF Sep 15 02:28:39.092 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/0 (ifh 0x10), area 0, protocol OSPF, weight 10 Sep 15 02:28:39.092 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2523: link GigabitEthernet0/0/0/0 (ifh 0x10), IGP OSPF area 0: no neighbor changes Sep 15 02:28:39.092 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x10) data changed Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x18), tot_nbrs 0, subnet type 0, flags 0x2 Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x18) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:935: Link GigabitEthernet0/0/0/1 (ifh 0x18): Added area 0 IGP OSPF Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1019: Link GigabitEthernet0/0/0/1 (ifh 0x18): Update nbrs for area 0 IGP OSPF Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/1 (ifh 0x18), area 0, protocol OSPF, weight 10 Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2523: link GigabitEthernet0/0/0/1 (ifh 0x18), IGP OSPF area 0: no neighbor changes Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x18) data changed Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x10) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/0 (ifh 0x10), tot_nbrs 0, subnet type 0, flags 0x2 Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x10) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2523: link GigabitEthernet0/0/0/0 (ifh 0x10), IGP OSPF area 0: no neighbor changes Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x18), tot_nbrs 0, subnet type 0, flags 0x2 Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x18) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:39.100 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2523: link GigabitEthernet0/0/0/1 (ifh 0x18), IGP OSPF area 0: no neighbor changes Sep 15 02:28:39.400 mpls_te/link 0/RP0/CPU0 t4581 Validating ifindexes for all links Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x18) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x18), tot_nbrs 0, subnet type 1, flags 0xb Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1019: Link GigabitEthernet0/0/0/1 (ifh 0x18): Update nbrs for area 0 IGP OSPF Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/1 (ifh 0x18), area 0, protocol 2, subnet type 1 Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x18) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2498: Link GigabitEthernet0/0/0/1 (ifh 0x18): update DB for 1 neighbors Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1559: Received neighbor 10.3.6.3 on link GigabitEthernet0/0/0/1 (ifh 0x18), IGP OSPF area 0 Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1578: Rcvd nbr node ID 3.3.3.3, state 1 Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1596: Matching existing neighbor found? False Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1714: Neighbor unknown: creating new one Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1286: link GigabitEthernet0/0/0/1 (ifh 0x18): setting IGP cost: 10, in area 0, protocol OSPF Sep 15 02:28:48.071 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1429: link GigabitEthernet0/0/0/1 (ifh 0x18), IGP OSPF area 0: subnet type changed to 1 Sep 15 02:28:48.072 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/1 (ifh 0x18) nbr addr 10.3.6.3, count 1, nbr state 1 Sep 15 02:28:48.072 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x18) data changed Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/0 (ifh 0x10) (0 nbrs) in area 0, IGP OSPF Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/0 (ifh 0x10), tot_nbrs 0, subnet type 1, flags 0xb Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1019: Link GigabitEthernet0/0/0/0 (ifh 0x10): Update nbrs for area 0 IGP OSPF Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/0 (ifh 0x10), area 0, protocol 2, subnet type 1 Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/0 (ifh 0x10) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:2498: Link GigabitEthernet0/0/0/0 (ifh 0x10): update DB for 1 neighbors Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1559: Received neighbor 10.5.6.5 on link GigabitEthernet0/0/0/0 (ifh 0x10), IGP OSPF area 0 Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1578: Rcvd nbr node ID 5.5.5.5, state 1 Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1596: Matching existing neighbor found? False Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1714: Neighbor unknown: creating new one Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1286: link GigabitEthernet0/0/0/0 (ifh 0x10): setting IGP cost: 10, in area 0, protocol OSPF Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1429: link GigabitEthernet0/0/0/0 (ifh 0x10), IGP OSPF area 0: subnet type changed to 1 Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/0 (ifh 0x10) nbr addr 10.5.6.5, count 2, nbr state 1 Sep 15 02:28:58.924 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/0 (ifh 0x10) data changed Sep 15 02:31:33.719 mpls_te/link 0/RP0/CPU0 t4581 LM_LINK:5471: Forced Flooding for IGP OSPF, area 0 RP/0/RP0/CPU0:P6#show mpls traffic-eng trace bselect Tue Sep 15 04:47:20.456 UTC 100 wrapping entries (67648 possible, 320 allocated, 0 filtered, 100 total) Sep 15 02:28:35.893 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.070 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.071 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.071 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.071 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.071 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.071 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.071 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.071 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.071 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.072 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.077 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:48.077 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:49.020 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:49.053 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:49.053 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:53.897 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:53.930 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:53.930 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:57.920 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:57.920 mpls_te/bselect 0/RP0/CPU0 t4581 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 t4581 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 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:58.660 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:58.927 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:28:58.930 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:30:48.991 mpls_te/bselect 0/RP0/CPU0 t4581 tebm_verify_bkup_db: Verifying existing bkup data Sep 15 02:49:36.071 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 02:49:36.071 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 03:06:40.258 mpls_te/bselect 0/RP0/CPU0 t4581 frr_event_lsp_down: T:0, L:6, prot_if GigabitEthernet0/0/0/0 (ifhndl 0x10) , NH:10.5.6.5 Sep 15 03:15:44.917 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 03:15:44.949 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 03:32:06.217 mpls_te/bselect 0/RP0/CPU0 t4581 frr_event_lsp_down: T:0, L:9, prot_if GigabitEthernet0/0/0/0 (ifhndl 0x10) , NH:10.5.6.5 Sep 15 03:32:06.268 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7058: frr_event_resv_arrive: T:0, L:8, backup None (ifh 0x0), NH:10.3.6.3 Sep 15 03:32:06.269 mpls_te/bselect 0/RP0/CPU0 t4581 te_frr_add_new_plsp: T:0, L:8 prot_if GigabitEthernet0/0/0/1 (ifhndl 0x18) , backup_if None (ifhndl 0x0) , protection None Sep 15 03:32:06.269 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 03:32:06.269 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 03:32:34.541 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7058: frr_event_resv_arrive: T:0, L:10, backup None (ifh 0x0), NH:10.5.6.5 Sep 15 03:32:34.541 mpls_te/bselect 0/RP0/CPU0 t4581 te_frr_add_new_plsp: T:0, L:10 prot_if GigabitEthernet0/0/0/0 (ifhndl 0x10) , backup_if None (ifhndl 0x0) , protection None Sep 15 03:32:34.541 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 03:32:34.541 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 03:35:54.951 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 03:35:54.981 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 03:35:54.981 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4296: frr_event_promote_intf_plsp: All intf processed: promotion complete .. Sep 15 03:39:24.484 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 03:39:24.485 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 03:39:24.664 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 03:39:24.664 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 03:39:28.696 mpls_te/bselect 0/RP0/CPU0 t4581 frr_event_lsp_down: T:200, L:2, prot_if GigabitEthernet0/0/0/1 (ifhndl 0x18) , NH:10.3.6.3 Sep 15 03:39:32.198 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 03:39:32.231 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 03:39:35.303 mpls_te/bselect 0/RP0/CPU0 t4581 frr_event_lsp_down: T:200, L:2, prot_if GigabitEthernet0/0/0/0 (ifhndl 0x10) , NH:10.5.6.5 Sep 15 03:39:41.958 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 03:39:41.958 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 03:39:51.985 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 03:39:52.015 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 03:39:52.015 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4296: frr_event_promote_intf_plsp: All intf processed: promotion complete .. Sep 15 03:41:28.418 mpls_te/bselect 0/RP0/CPU0 t4581 frr_event_lsp_down: T:0, L:8, prot_if GigabitEthernet0/0/0/1 (ifhndl 0x18) , NH:10.3.6.3 Sep 15 03:41:35.124 mpls_te/bselect 0/RP0/CPU0 t4581 frr_event_lsp_down: T:0, L:10, prot_if GigabitEthernet0/0/0/0 (ifhndl 0x10) , NH:10.5.6.5 Sep 15 03:44:51.985 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 03:49:51.985 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 03:54:51.986 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 03:59:51.986 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 04:04:51.986 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 04:07:51.685 mpls_te/bselect 0/RP0/CPU0 t4581 frr_event_lsp_down: T:1000, L:2, prot_if GigabitEthernet0/0/0/1 (ifhndl 0x18) , NH:10.3.6.3 Sep 15 04:09:51.987 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 04:14:51.987 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 04:19:51.987 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 04:22:20.697 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7058: frr_event_resv_arrive: T:0, L:4, backup None (ifh 0x0), NH:10.5.6.5 Sep 15 04:22:20.697 mpls_te/bselect 0/RP0/CPU0 t4581 te_frr_add_new_plsp: T:0, L:4 prot_if GigabitEthernet0/0/0/0 (ifhndl 0x10) , backup_if None (ifhndl 0x0) , protection None Sep 15 04:22:20.697 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 04:22:20.697 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 04:22:22.860 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7058: frr_event_resv_arrive: T:0, L:4, backup None (ifh 0x0), NH:10.3.6.3 Sep 15 04:22:22.860 mpls_te/bselect 0/RP0/CPU0 t4581 te_frr_add_new_plsp: T:0, L:4 prot_if GigabitEthernet0/0/0/1 (ifhndl 0x18) , backup_if None (ifhndl 0x0) , protection None Sep 15 04:22:22.860 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 04:22:22.860 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 04:24:51.987 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 04:29:51.988 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 04:30:55.969 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 04:30:55.969 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 04:30:56.886 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 04:30:56.886 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7897: frr_plsp_promote_backup: cur promo timer 300, delay 10 sec, 0 ms Sep 15 04:31:06.886 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 04:31:06.916 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 04:31:06.916 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4296: frr_event_promote_intf_plsp: All intf processed: promotion complete .. Sep 15 04:32:24.190 mpls_te/bselect 0/RP0/CPU0 t4581 frr_event_lsp_down: T:1001, L:3, prot_if GigabitEthernet0/0/0/1 (ifhndl 0x18) , NH:10.3.6.3 Sep 15 04:32:30.079 mpls_te/bselect 0/RP0/CPU0 t4581 frr_event_lsp_down: T:1001, L:3, prot_if GigabitEthernet0/0/0/0 (ifhndl 0x10) , NH:10.5.6.5 Sep 15 04:33:45.349 mpls_te/bselect 0/RP0/CPU0 t4581 frr_event_lsp_down: T:0, L:4, prot_if GigabitEthernet0/0/0/1 (ifhndl 0x18) , NH:10.3.6.3 Sep 15 04:33:48.957 mpls_te/bselect 0/RP0/CPU0 t4581 frr_event_lsp_down: T:0, L:4, prot_if GigabitEthernet0/0/0/0 (ifhndl 0x10) , NH:10.5.6.5 Sep 15 04:36:06.886 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 04:41:05.403 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7058: frr_event_resv_arrive: T:0, L:6, backup None (ifh 0x0), NH:10.5.6.5 Sep 15 04:41:05.403 mpls_te/bselect 0/RP0/CPU0 t4581 te_frr_add_new_plsp: T:0, L:6 prot_if GigabitEthernet0/0/0/0 (ifhndl 0x10) , backup_if None (ifhndl 0x0) , protection None Sep 15 04:41:05.403 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 04:41:05.403 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 04:41:06.887 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... Sep 15 04:41:07.461 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:7058: frr_event_resv_arrive: T:0, L:6, backup None (ifh 0x0), NH:10.3.6.3 Sep 15 04:41:07.461 mpls_te/bselect 0/RP0/CPU0 t4581 te_frr_add_new_plsp: T:0, L:6 prot_if GigabitEthernet0/0/0/1 (ifhndl 0x18) , backup_if None (ifhndl 0x0) , protection None Sep 15 04:41:07.461 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:6883: te_frr_add_new_plsp: adding T:0, in no_bkup Sep 15 04:41:07.461 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:3665: te_frr_find_backup: find backup bw 0, bw_type 2 Sep 15 04:46:06.887 mpls_te/bselect 0/RP0/CPU0 t4581 FRR:4620: frr_event_promote_intf: All intf processed: promotion complete ...... RP/0/RP0/CPU0:P6#show rsvp trace signalling Tue Sep 15 04:47:20.618 UTC 211 wrapping entries (264256 possible, 320 allocated, 0 filtered, 211 total) Sep 15 03:06:40.121 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 240, IP src: 22.22.22.22 IP dst: 11.11.11.11, psbs: 0, rsbs: 0 Sep 15 03:06:40.121 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:6) Sep 15 03:06:40.135 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000008, local_rid:6.6.6.6, nbor:5.5.5.5, nhop:10.5.6.5, next_ifh:GigabitEthernet0/0/0/0 (ifh 0x10), obj_len: 204 Sep 15 03:06:40.135 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:6) Sep 15 03:06:40.254 rsvp/sig 0/RP0/CPU0 t4414 SIG:9276: PathErr: from network for dst (11.11.11.11:0), src (22.22.22.22:6); PSB flags: 0xc0000008 Sep 15 03:06:40.254 rsvp/sig 0/RP0/CPU0 t4414 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.254 rsvp/sig 0/RP0/CPU0 t4414 SIG:3929: PATH destroy: dst (11.11.11.11:0), src (22.22.22.22:6) reason: (16): State deleted due to PERR w/ PSR from network Sep 15 03:06:40.254 rsvp/sig 0/RP0/CPU0 t4414 SIG:3931: PATH destroy: flags 0x40000, psb flags 0xc0000008, pfc flags 0x80080001 Sep 15 03:24:45.813 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 196, IP src: 1.1.1.1 IP dst: 3.3.3.3, psbs: 0, rsbs: 0 Sep 15 03:24:45.813 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (3.3.3.3:200), src (1.1.1.1:2) Sep 15 03:24:45.820 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000008, local_rid:6.6.6.6, nbor:3.3.3.3, nhop:10.3.6.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x18), obj_len: 176 Sep 15 03:24:45.820 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (3.3.3.3:200), src (1.1.1.1:2) Sep 15 03:24:45.837 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x18), in GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 112, IP src: 10.3.6.3, psbs: 1, rsbs: 0 Sep 15 03:24:45.837 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (3.3.3.3:200), src (1.1.1.1:2) Sep 15 03:24:45.848 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000008, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x50, wedged 0 Sep 15 03:24:45.848 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 200, src 1.1.1.1 id 2 Sep 15 03:24:45.848 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000008, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x70, wedged 0 Sep 15 03:24:45.849 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (3.3.3.3:200), src (1.1.1.1:2) Sep 15 03:24:45.849 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (3.3.3.3:200), src (1.1.1.1:2) Sep 15 03:24:53.387 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 224, IP src: 10.3.6.3 IP dst: 10.3.6.6, psbs: 0, rsbs: 0 Sep 15 03:24:53.387 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (1.1.1.1:200), src (3.3.3.3:2) Sep 15 03:24:53.393 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:6.6.6.6, nbor:5.5.5.5, nhop:10.5.6.5, next_ifh:GigabitEthernet0/0/0/0 (ifh 0x10), obj_len: 192 Sep 15 03:24:53.393 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (1.1.1.1:200), src (3.3.3.3:2) Sep 15 03:24:53.452 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/0 (ifh 0x10), in GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 112, IP src: 10.5.6.5, psbs: 1, rsbs: 0 Sep 15 03:24:53.452 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (1.1.1.1:200), src (3.3.3.3:2) Sep 15 03:24:53.460 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 03:24:53.460 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x10), current (0 Bps), port 200, src 3.3.3.3 id 2 Sep 15 03:24:53.460 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0x70, wedged 0 Sep 15 03:24:53.460 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (1.1.1.1:200), src (3.3.3.3:2) Sep 15 03:24:53.460 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (1.1.1.1:200), src (3.3.3.3:2) Sep 15 03:25:17.699 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x70, wedged 0 Sep 15 03:25:17.699 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (3.3.3.3:200), src (1.1.1.1:2) Sep 15 03:25:17.699 rsvp/sig 0/RP0/CPU0 t4414 SIG:804: PATH Tunnel IPv4 changed: dst (3.3.3.3:200), src (1.1.1.1:2) Sep 15 03:32:03.906 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 252, IP src: 10.3.6.3 IP dst: 10.3.6.6, psbs: 0, rsbs: 0 Sep 15 03:32:03.906 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:9) Sep 15 03:32:03.914 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:6.6.6.6, nbor:5.5.5.5, nhop:10.5.6.5, next_ifh:GigabitEthernet0/0/0/0 (ifh 0x10), obj_len: 204 Sep 15 03:32:03.914 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:9) Sep 15 03:32:06.214 rsvp/sig 0/RP0/CPU0 t4414 SIG:3929: PATH destroy: dst (11.11.11.11:0), src (22.22.22.22:9) reason: (2): State deleted due to signaling Sep 15 03:32:06.214 rsvp/sig 0/RP0/CPU0 t4414 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 03:32:06.220 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 252, IP src: 10.5.6.5 IP dst: 10.5.6.6, psbs: 0, rsbs: 0 Sep 15 03:32:06.220 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:8) Sep 15 03:32:06.226 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:6.6.6.6, nbor:3.3.3.3, nhop:10.3.6.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x18), obj_len: 188 Sep 15 03:32:06.226 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:8) Sep 15 03:32:06.263 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x18), in GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 180, IP src: 10.3.6.3, psbs: 1, rsbs: 0 Sep 15 03:32:06.263 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:8) Sep 15 03:32:06.273 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x50, wedged 0 Sep 15 03:32:06.273 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 0, src 11.11.11.11 id 8 Sep 15 03:32:06.273 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 6.6.6.6/5.5.5.5, obj len: 0xb4, wedged 0 Sep 15 03:32:06.274 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:8) Sep 15 03:32:06.274 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (22.22.22.22:0), src (11.11.11.11:8) Sep 15 03:32:34.400 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 252, IP src: 10.3.6.3 IP dst: 10.3.6.6, psbs: 0, rsbs: 0 Sep 15 03:32:34.401 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:10) Sep 15 03:32:34.407 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:6.6.6.6, nbor:5.5.5.5, nhop:10.5.6.5, next_ifh:GigabitEthernet0/0/0/0 (ifh 0x10), obj_len: 204 Sep 15 03:32:34.407 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:10) Sep 15 03:32:34.536 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/0 (ifh 0x10), in GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 244, IP src: 10.5.6.5, psbs: 1, rsbs: 0 Sep 15 03:32:34.536 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:10) Sep 15 03:32:34.545 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 03:32:34.545 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x10), current (0 Bps), port 0, src 22.22.22.22 id 10 Sep 15 03:32:34.545 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0xf4, wedged 0 Sep 15 03:32:34.545 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:10) Sep 15 03:32:34.545 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (11.11.11.11:0), src (22.22.22.22:10) Sep 15 03:39:28.691 rsvp/sig 0/RP0/CPU0 t4414 SIG:3929: PATH destroy: dst (3.3.3.3:200), src (1.1.1.1:2) reason: (2): State deleted due to signaling Sep 15 03:39:28.691 rsvp/sig 0/RP0/CPU0 t4414 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 03:39:28.692 rsvp/sig 0/RP0/CPU0 t4414 SIG:1214: RESV destroy: dst (3.3.3.3:200), src (1.1.1.1:2) reason: (2): State deleted due to signaling Sep 15 03:39:28.692 rsvp/sig 0/RP0/CPU0 t4414 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0000020, request flags 0x86000844 Sep 15 03:39:28.692 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 200, src 1.1.1.1 id 2 Sep 15 03:39:35.299 rsvp/sig 0/RP0/CPU0 t4414 SIG:3929: PATH destroy: dst (1.1.1.1:200), src (3.3.3.3:2) reason: (2): State deleted due to signaling Sep 15 03:39:35.299 rsvp/sig 0/RP0/CPU0 t4414 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 03:39:35.299 rsvp/sig 0/RP0/CPU0 t4414 SIG:1214: RESV destroy: dst (1.1.1.1:200), src (3.3.3.3:2) reason: (2): State deleted due to signaling Sep 15 03:39:35.299 rsvp/sig 0/RP0/CPU0 t4414 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0000020, request flags 0x86000844 Sep 15 03:39:35.299 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x10), current (0 Bps), port 200, src 3.3.3.3 id 2 Sep 15 03:41:28.414 rsvp/sig 0/RP0/CPU0 t4414 SIG:3929: PATH destroy: dst (22.22.22.22:0), src (11.11.11.11:8) reason: (2): State deleted due to signaling Sep 15 03:41:28.414 rsvp/sig 0/RP0/CPU0 t4414 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 03:41:28.415 rsvp/sig 0/RP0/CPU0 t4414 SIG:1214: RESV destroy: dst (22.22.22.22:0), src (11.11.11.11:8) reason: (2): State deleted due to signaling Sep 15 03:41:28.415 rsvp/sig 0/RP0/CPU0 t4414 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0000020, request flags 0x86003844 Sep 15 03:41:28.415 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 0, src 11.11.11.11 id 8 Sep 15 03:41:35.120 rsvp/sig 0/RP0/CPU0 t4414 SIG:3929: PATH destroy: dst (11.11.11.11:0), src (22.22.22.22:10) reason: (2): State deleted due to signaling Sep 15 03:41:35.120 rsvp/sig 0/RP0/CPU0 t4414 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 03:41:35.120 rsvp/sig 0/RP0/CPU0 t4414 SIG:1214: RESV destroy: dst (11.11.11.11:0), src (22.22.22.22:10) reason: (2): State deleted due to signaling Sep 15 03:41:35.120 rsvp/sig 0/RP0/CPU0 t4414 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0000020, request flags 0x86003844 Sep 15 03:41:35.120 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x10), current (0 Bps), port 0, src 22.22.22.22 id 10 Sep 15 04:00:24.146 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 220, IP src: 1.1.1.1 IP dst: 3.3.3.3, psbs: 0, rsbs: 0 Sep 15 04:00:24.147 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (3.3.3.3:1000), src (1.1.1.1:2) Sep 15 04:00:24.153 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000008, local_rid:6.6.6.6, nbor:3.3.3.3, nhop:10.3.6.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x18), obj_len: 200 Sep 15 04:00:24.154 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (3.3.3.3:1000), src (1.1.1.1:2) Sep 15 04:00:24.169 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x18), in GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 112, IP src: 10.3.6.3, psbs: 1, rsbs: 0 Sep 15 04:00:24.169 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (3.3.3.3:1000), src (1.1.1.1:2) Sep 15 04:00:24.177 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000008, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x50, wedged 0 Sep 15 04:00:24.177 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 1000, src 1.1.1.1 id 2 Sep 15 04:00:24.177 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000008, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x70, wedged 0 Sep 15 04:00:24.177 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (3.3.3.3:1000), src (1.1.1.1:2) Sep 15 04:00:24.178 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (3.3.3.3:1000), src (1.1.1.1:2) Sep 15 04:00:57.850 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x70, wedged 0 Sep 15 04:00:57.850 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (3.3.3.3:1000), src (1.1.1.1:2) Sep 15 04:00:57.850 rsvp/sig 0/RP0/CPU0 t4414 SIG:804: PATH Tunnel IPv4 changed: dst (3.3.3.3:1000), src (1.1.1.1:2) Sep 15 04:07:51.681 rsvp/sig 0/RP0/CPU0 t4414 SIG:3929: PATH destroy: dst (3.3.3.3:1000), src (1.1.1.1:2) reason: (2): State deleted due to signaling Sep 15 04:07:51.681 rsvp/sig 0/RP0/CPU0 t4414 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 04:07:51.681 rsvp/sig 0/RP0/CPU0 t4414 SIG:1214: RESV destroy: dst (3.3.3.3:1000), src (1.1.1.1:2) reason: (2): State deleted due to signaling Sep 15 04:07:51.681 rsvp/sig 0/RP0/CPU0 t4414 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0000020, request flags 0x86000844 Sep 15 04:07:51.681 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 1000, src 1.1.1.1 id 2 Sep 15 04:22:20.167 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 220, IP src: 1.1.1.1 IP dst: 2.2.2.2, psbs: 0, rsbs: 0 Sep 15 04:22:20.167 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (2.2.2.2:1001), src (1.1.1.1:3) Sep 15 04:22:20.177 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000008, local_rid:6.6.6.6, nbor:3.3.3.3, nhop:10.3.6.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x18), obj_len: 200 Sep 15 04:22:20.177 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (2.2.2.2:1001), src (1.1.1.1:3) Sep 15 04:22:20.215 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x18), in GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 112, IP src: 3.3.3.3, psbs: 1, rsbs: 0 Sep 15 04:22:20.215 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (2.2.2.2:1001), src (1.1.1.1:3) Sep 15 04:22:20.223 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000008, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x50, wedged 0 Sep 15 04:22:20.223 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 1001, src 1.1.1.1 id 3 Sep 15 04:22:20.223 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000008, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x70, wedged 0 Sep 15 04:22:20.223 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (2.2.2.2:1001), src (1.1.1.1:3) Sep 15 04:22:20.223 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (2.2.2.2:1001), src (1.1.1.1:3) Sep 15 04:22:20.365 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 240, IP src: 10.3.6.3 IP dst: 10.3.6.6, psbs: 0, rsbs: 0 Sep 15 04:22:20.365 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (1.1.1.1:1001), src (2.2.2.2:3) Sep 15 04:22:20.371 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:6.6.6.6, nbor:5.5.5.5, nhop:10.5.6.5, next_ifh:GigabitEthernet0/0/0/0 (ifh 0x10), obj_len: 208 Sep 15 04:22:20.371 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (1.1.1.1:1001), src (2.2.2.2:3) Sep 15 04:22:20.483 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 252, IP src: 10.3.6.3 IP dst: 10.3.6.6, psbs: 0, rsbs: 0 Sep 15 04:22:20.483 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 04:22:20.489 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:6.6.6.6, nbor:5.5.5.5, nhop:10.5.6.5, next_ifh:GigabitEthernet0/0/0/0 (ifh 0x10), obj_len: 204 Sep 15 04:22:20.489 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 04:22:20.499 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/0 (ifh 0x10), in GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 112, IP src: 10.5.6.5, psbs: 1, rsbs: 0 Sep 15 04:22:20.499 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (1.1.1.1:1001), src (2.2.2.2:3) Sep 15 04:22:20.506 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 04:22:20.506 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x10), current (0 Bps), port 1001, src 2.2.2.2 id 3 Sep 15 04:22:20.506 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0x70, wedged 0 Sep 15 04:22:20.507 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (1.1.1.1:1001), src (2.2.2.2:3) Sep 15 04:22:20.507 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (1.1.1.1:1001), src (2.2.2.2:3) Sep 15 04:22:20.693 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/0 (ifh 0x10), in GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 244, IP src: 10.5.6.5, psbs: 1, rsbs: 0 Sep 15 04:22:20.693 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 04:22:20.701 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 04:22:20.701 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x10), current (0 Bps), port 0, src 22.22.22.22 id 4 Sep 15 04:22:20.701 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0xf4, wedged 0 Sep 15 04:22:20.701 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 04:22:20.701 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (11.11.11.11:0), src (22.22.22.22:4) Sep 15 04:22:22.812 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 252, IP src: 10.5.6.5 IP dst: 10.5.6.6, psbs: 0, rsbs: 0 Sep 15 04:22:22.812 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 04:22:22.818 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:6.6.6.6, nbor:3.3.3.3, nhop:10.3.6.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x18), obj_len: 188 Sep 15 04:22:22.818 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 04:22:22.856 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x18), in GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 180, IP src: 10.3.6.3, psbs: 1, rsbs: 0 Sep 15 04:22:22.856 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 04:22:22.864 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x50, wedged 0 Sep 15 04:22:22.864 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 0, src 11.11.11.11 id 4 Sep 15 04:22:22.864 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 6.6.6.6/5.5.5.5, obj len: 0xb4, wedged 0 Sep 15 04:22:22.864 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 04:22:22.864 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (22.22.22.22:0), src (11.11.11.11:4) Sep 15 04:22:49.492 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x70, wedged 0 Sep 15 04:22:49.492 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (2.2.2.2:1001), src (1.1.1.1:3) Sep 15 04:22:49.492 rsvp/sig 0/RP0/CPU0 t4414 SIG:804: PATH Tunnel IPv4 changed: dst (2.2.2.2:1001), src (1.1.1.1:3) Sep 15 04:32:24.186 rsvp/sig 0/RP0/CPU0 t4414 SIG:3929: PATH destroy: dst (2.2.2.2:1001), src (1.1.1.1:3) reason: (2): State deleted due to signaling Sep 15 04:32:24.186 rsvp/sig 0/RP0/CPU0 t4414 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 04:32:24.186 rsvp/sig 0/RP0/CPU0 t4414 SIG:1214: RESV destroy: dst (2.2.2.2:1001), src (1.1.1.1:3) reason: (2): State deleted due to signaling Sep 15 04:32:24.186 rsvp/sig 0/RP0/CPU0 t4414 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0000020, request flags 0x86000844 Sep 15 04:32:24.186 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 1001, src 1.1.1.1 id 3 Sep 15 04:32:24.195 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 232, IP src: 10.5.6.5 IP dst: 10.5.6.6, psbs: 0, rsbs: 0 Sep 15 04:32:24.195 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (2.2.2.2:1001), src (1.1.1.1:5) Sep 15 04:32:24.202 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:6.6.6.6, nbor:3.3.3.3, nhop:10.3.6.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x18), obj_len: 200 Sep 15 04:32:24.202 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (2.2.2.2:1001), src (1.1.1.1:5) Sep 15 04:32:24.236 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x18), in GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 112, IP src: 10.3.6.3, psbs: 1, rsbs: 0 Sep 15 04:32:24.237 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (2.2.2.2:1001), src (1.1.1.1:5) Sep 15 04:32:24.244 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x50, wedged 0 Sep 15 04:32:24.244 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 1001, src 1.1.1.1 id 5 Sep 15 04:32:24.244 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x70, wedged 0 Sep 15 04:32:24.244 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (2.2.2.2:1001), src (1.1.1.1:5) Sep 15 04:32:24.245 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (2.2.2.2:1001), src (1.1.1.1:5) Sep 15 04:32:30.075 rsvp/sig 0/RP0/CPU0 t4414 SIG:3929: PATH destroy: dst (1.1.1.1:1001), src (2.2.2.2:3) reason: (2): State deleted due to signaling Sep 15 04:32:30.075 rsvp/sig 0/RP0/CPU0 t4414 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 04:32:30.075 rsvp/sig 0/RP0/CPU0 t4414 SIG:1214: RESV destroy: dst (1.1.1.1:1001), src (2.2.2.2:3) reason: (2): State deleted due to signaling Sep 15 04:32:30.075 rsvp/sig 0/RP0/CPU0 t4414 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0000020, request flags 0x86000844 Sep 15 04:32:30.075 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x10), current (0 Bps), port 1001, src 2.2.2.2 id 3 Sep 15 04:32:30.080 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 240, IP src: 10.3.6.3 IP dst: 10.3.6.6, psbs: 0, rsbs: 0 Sep 15 04:32:30.080 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (1.1.1.1:1001), src (2.2.2.2:4) Sep 15 04:32:30.086 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:6.6.6.6, nbor:5.5.5.5, nhop:10.5.6.5, next_ifh:GigabitEthernet0/0/0/0 (ifh 0x10), obj_len: 208 Sep 15 04:32:30.086 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (1.1.1.1:1001), src (2.2.2.2:4) Sep 15 04:32:30.144 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/0 (ifh 0x10), in GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 112, IP src: 10.5.6.5, psbs: 1, rsbs: 0 Sep 15 04:32:30.144 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (1.1.1.1:1001), src (2.2.2.2:4) Sep 15 04:32:30.151 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 04:32:30.151 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x10), current (0 Bps), port 1001, src 2.2.2.2 id 4 Sep 15 04:32:30.151 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0x70, wedged 0 Sep 15 04:32:30.152 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (1.1.1.1:1001), src (2.2.2.2:4) Sep 15 04:32:30.152 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (1.1.1.1:1001), src (2.2.2.2:4) Sep 15 04:33:45.346 rsvp/sig 0/RP0/CPU0 t4414 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 04:33:45.346 rsvp/sig 0/RP0/CPU0 t4414 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 04:33:45.346 rsvp/sig 0/RP0/CPU0 t4414 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 04:33:45.346 rsvp/sig 0/RP0/CPU0 t4414 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0000020, request flags 0x86003844 Sep 15 04:33:45.346 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 0, src 11.11.11.11 id 4 Sep 15 04:33:48.953 rsvp/sig 0/RP0/CPU0 t4414 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 04:33:48.953 rsvp/sig 0/RP0/CPU0 t4414 SIG:3931: PATH destroy: flags 0x0, psb flags 0xc0000018, pfc flags 0x80180009 Sep 15 04:33:48.953 rsvp/sig 0/RP0/CPU0 t4414 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 04:33:48.953 rsvp/sig 0/RP0/CPU0 t4414 SIG:1216: RESV destroy: flags 0x190000, rsb flags 0xc0000020, request flags 0x86003844 Sep 15 04:33:48.953 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x10), current (0 Bps), port 0, src 22.22.22.22 id 4 Sep 15 04:41:05.089 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 252, IP src: 10.3.6.3 IP dst: 10.3.6.6, psbs: 0, rsbs: 0 Sep 15 04:41:05.089 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:6) Sep 15 04:41:05.096 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:6.6.6.6, nbor:5.5.5.5, nhop:10.5.6.5, next_ifh:GigabitEthernet0/0/0/0 (ifh 0x10), obj_len: 204 Sep 15 04:41:05.096 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:6) Sep 15 04:41:05.398 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/0 (ifh 0x10), in GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 244, IP src: 10.5.6.5, psbs: 1, rsbs: 0 Sep 15 04:41:05.399 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (11.11.11.11:0), src (22.22.22.22:6) Sep 15 04:41:05.407 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0x50, wedged 0 Sep 15 04:41:05.407 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/0 (ifh 0x10), current (0 Bps), port 0, src 22.22.22.22 id 6 Sep 15 04:41:05.407 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 6.6.6.6/3.3.3.3, obj len: 0xf4, wedged 0 Sep 15 04:41:05.408 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (11.11.11.11:0), src (22.22.22.22:6) Sep 15 04:41:05.408 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (11.11.11.11:0), src (22.22.22.22:6) Sep 15 04:41:07.414 rsvp/sig 0/RP0/CPU0 t4414 SIG:4549: PATH creating network: GigabitEthernet0/0/0/0 (ifh 0x10), obj len: 252, IP src: 10.5.6.5 IP dst: 10.5.6.6, psbs: 0, rsbs: 0 Sep 15 04:41:07.414 rsvp/sig 0/RP0/CPU0 t4414 SIG:721: PATH Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:6) Sep 15 04:41:07.420 rsvp/sig 0/RP0/CPU0 t4414 SIG:2836: PATH outgoing creating: flags:0xc0000018, local_rid:6.6.6.6, nbor:3.3.3.3, nhop:10.3.6.3, next_ifh:GigabitEthernet0/0/0/1 (ifh 0x18), obj_len: 188 Sep 15 04:41:07.420 rsvp/sig 0/RP0/CPU0 t4414 SIG:887: PATH Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:6) Sep 15 04:41:07.456 rsvp/sig 0/RP0/CPU0 t4414 SIG:4654: RESV creating network: GigabitEthernet0/0/0/1 (ifh 0x18), in GigabitEthernet0/0/0/1 (ifh 0x18), obj len: 180, IP src: 10.3.6.3, psbs: 1, rsbs: 0 Sep 15 04:41:07.457 rsvp/sig 0/RP0/CPU0 t4414 SIG:362: RESV Tunnel IPv4 created: dst (22.22.22.22:0), src (11.11.11.11:6) Sep 15 04:41:07.464 rsvp/sig 0/RP0/CPU0 t4414 SIG:4130: RESV outgoing creating: rsb flags: 0xc0000020, psb flags: 0xc0000018, local/nbor 6.6.6.6/5.5.5.5, obj len: 0x50, wedged 0 Sep 15 04:41:07.464 rsvp/sig 0/RP0/CPU0 t4414 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/1 (ifh 0x18), current (0 Bps), port 0, src 11.11.11.11 id 6 Sep 15 04:41:07.464 rsvp/sig 0/RP0/CPU0 t4414 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000120, psb flags: 0xc0000018, local/nbor 6.6.6.6/5.5.5.5, obj len: 0xb4, wedged 0 Sep 15 04:41:07.464 rsvp/sig 0/RP0/CPU0 t4414 SIG:528: RESV Tunnel IPv4 outgoing: dst (22.22.22.22:0), src (11.11.11.11:6) Sep 15 04:41:07.465 rsvp/sig 0/RP0/CPU0 t4414 SIG:492: Assigned backup None (ifh 0x0): dst (22.22.22.22:0), src (11.11.11.11:6)