RP/0/RP0/CPU0:PE1#show mpls traffic-eng trace head-end Sun Sep 13 08:58:56.271 UTC 52 wrapping entries (264256 possible, 320 allocated, 0 filtered, 52 total) Sep 13 08:18:28.219 mpls_te/head-end 0/RP0/CPU0 t4491 [PCALC-ECMP] Max BWV Allocated: 20 Sep 13 08:18:28.219 mpls_te/head-end 0/RP0/CPU0 t4491 [PCALC-ECMP] Max BWVE Allocated: 15 Sep 13 08:18:31.507 mpls_te/head-end 0/RP0/CPU0 t4491 NOTFN OwnedResEnd Sep 13 08:18:33.407 mpls_te/head-end 0/RP0/CPU0 t4491 Validating ifindexes for all vifs Sep 13 08:29:49.912 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, Start sync retry timer Sep 13 08:29:49.912 mpls_te/head-end 0/RP0/CPU0 t4491 Batch op IF CREATE with 1 items Sep 13 08:29:49.918 mpls_te/head-end 0/RP0/CPU0 t4491 ifh:0x1c, NOTFN INITIAL; caps ; proto NONE; state not ready Sep 13 08:29:49.919 mpls_te/head-end 0/RP0/CPU0 t4491 ifh:0x1c, NOTFN INITIAL; caps mpls_te; proto NONE; state not ready Sep 13 08:29:49.919 mpls_te/head-end 0/RP0/CPU0 t4491 Batch op CAPS ADD with 1 items Sep 13 08:29:49.920 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, ifh:0x1c, NOTFN STATE, caps ; proto NONE; state down Sep 13 08:29:49.920 mpls_te/head-end 0/RP0/CPU0 t4491 Batch op STATE UPDATE with 1 items Sep 13 08:29:49.922 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, IM op STATE UPDATE for tunnel-te0 (ifhndl 0x1c) Sep 13 08:29:49.923 mpls_te/head-end 0/RP0/CPU0 t4491 ifh:0x1c, NOTFN INITIAL; caps mpls; proto mpls; state not ready Sep 13 08:29:49.923 mpls_te/head-end 0/RP0/CPU0 t4491 ifh:0x1c, NOTFN MTU; caps mpls; proto mpls; mtu 1500 Sep 13 08:29:49.923 mpls_te/head-end 0/RP0/CPU0 t4491 Batch op MTU UPDATE with 1 items Sep 13 08:29:49.923 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, IM op CAPS ADD for tunnel-te0 (ifhndl 0x1c) Sep 13 08:29:49.923 mpls_te/head-end 0/RP0/CPU0 t4491 Batch op CAPS ADD with 1 items Sep 13 08:29:49.924 mpls_te/head-end 0/RP0/CPU0 t4491 ifh:0x1c, NOTFN CREATE; caps ipv4; proto ipv4; Sep 13 08:29:49.924 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, IP address is now available Sep 13 08:29:49.924 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, handling vif (0x87031d38) scheduled action ACT_CHECK Sep 13 08:29:50.017 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, IM op CAPS ADD for tunnel-te0 (ifhndl 0x1c) Sep 13 08:29:50.113 mpls_te/head-end 0/RP0/CPU0 t4491 tunnel-te0 (ifhndl 0x1c) : queued IM dest update: 9.9.9.9 Sep 13 08:29:50.113 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, Send IM attribute: old dest = 0.0.0.0, new dest = 9.9.9.9 Sep 13 08:29:50.114 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, Start sync retry timer Sep 13 08:29:50.114 mpls_te/head-end 0/RP0/CPU0 t4491 T:0 Configured classic Affinity: value 0x2 mask 0x2 Sep 13 08:29:50.115 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, Start sync retry timer Sep 13 08:29:50.115 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, POIndex:10, POType:1, ComputationType:0, Holddown:0 Sep 13 08:29:50.118 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, handling vif (0x87031d38) scheduled action ACT_CHECK Sep 13 08:29:50.119 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, handling vif (0x87031d38) scheduled action ACT_MODIFY_IN_PLACE Sep 13 08:29:50.119 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, handling vif (0x87031d38) scheduled action ACTION_VERIFY_SR Sep 13 08:29:50.258 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, L:2, LL:24014, TE rewrite queueing succeeded Sep 13 08:29:50.259 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, [CURRENT] L:2, ifh:0x1c, TE rewrite returned TRUE Sep 13 08:29:50.259 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, IFH:0x01C, moving state to up Sep 13 08:29:50.259 mpls_te/head-end 0/RP0/CPU0 t4491 Batch op STATE UPDATE with 1 items Sep 13 08:29:50.260 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, IM op STATE UPDATE for tunnel-te0 (ifhndl 0x1c) Sep 13 08:29:50.913 mpls_te/head-end 0/RP0/CPU0 t4491 Validating ifindexes for all vifs Sep 13 08:29:50.913 mpls_te/head-end 0/RP0/CPU0 t4491 Bulk ifindex lookup for 1 vifs Sep 13 08:29:50.918 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, ifindex set to 9 Sep 13 08:30:05.115 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, Sync retry timer expired Sep 13 08:39:21.938 mpls_te/head-end 0/RP0/CPU0 t4491 Created autoroute entry 9.9.9.9, area 2 0; total AA entries 1 Sep 13 08:39:21.938 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, added AA, D:9.9.9.9, IGP: 2, area 0 Sep 13 08:39:21.939 mpls_te/head-end 0/RP0/CPU0 t4491 Sent autoroute-announce tunnel list for 9.9.9.9 to IGP: OSPF, area 0: 1 tunnels Sep 13 08:39:22.936 mpls_te/head-end 0/RP0/CPU0 t4491 Validating ifindexes for all vifs Sep 13 08:49:01.242 mpls_te/head-end 0/RP0/CPU0 t4491 Removed tunnel T:0, D:9.9.9.9 from autoroute list for IGP: OSPF, area 0 Sep 13 08:49:01.244 mpls_te/head-end 0/RP0/CPU0 t4491 Sent autoroute-announce tunnel list for 9.9.9.9 to IGP: OSPF, area 0: 0 tunnels Sep 13 08:49:01.244 mpls_te/head-end 0/RP0/CPU0 t4491 Deleted autoroute entry 9.9.9.9, area 2 0; total AA entries 0 Sep 13 08:49:01.261 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, FA Update: Announcing Fwd Adj for D:9.9.9.9 Sep 13 08:49:01.261 mpls_te/head-end 0/RP0/CPU0 t4491 Created autoroute entry 9.9.9.9, area 2 0; total AA entries 1 Sep 13 08:49:01.261 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, FA Announced: yes Sep 13 08:49:01.261 mpls_te/head-end 0/RP0/CPU0 t4491 T:0, Type:TE, added AA, D:9.9.9.9, IGP: 2, area 0 Sep 13 08:49:01.305 mpls_te/head-end 0/RP0/CPU0 t4491 Sent autoroute-announce tunnel list for 9.9.9.9 to IGP: OSPF, area 0: 1 tunnels Sep 13 08:49:02.260 mpls_te/head-end 0/RP0/CPU0 t4491 Validating ifindexes for all vifs RP/0/RP0/CPU0:PE1#show mpls traffic-eng trace link Sun Sep 13 08:58:56.515 UTC 289 wrapping entries (67648 possible, 576 allocated, 0 filtered, 289 total) Sep 13 08:18:28.219 mpls_te/link 0/RP0/CPU0 t4491 DS-TE mode change: prev - 0, new - 1 Sep 13 08:18:28.219 mpls_te/link 0/RP0/CPU0 t4491 TE process restarting; aborting DS-TE mode change Sep 13 08:18:28.717 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:381: lm_iarm_control_cb_fn: connection to IARM established. Sep 13 08:18:31.920 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry Sep 13 08:18:31.920 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/1 (ifh 0x20) into db based on name and handle Sep 13 08:18:31.920 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x20), flood 0, force 0, Nbr cnt 0 Sep 13 08:18:31.920 mpls_te/link 0/RP0/CPU0 t4491 RSI: Registering interface GigabitEthernet0/0/0/1 (ifhndl 0x20) (0x20) for SRLG Notification Sep 13 08:18:31.920 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/1 (ifh 0x20) Created [1 links total], enable 0 Sep 13 08:18:31.922 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x20) BW attribute 0 kbps Sep 13 08:18:31.922 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x20) physical_bw 125000000 Bps Sep 13 08:18:32.007 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:18:32.007 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) pool1_bw 0 (Kbps) Sep 13 08:18:32.007 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x20), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.007 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x20), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.007 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x20), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.007 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/1 (ifh 0x20), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.007 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/1 (ifh 0x20) state down Sep 13 08:18:32.014 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/1 (ifh 0x20) to IARM batch Sep 13 08:18:32.014 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:756: lm_iarm_reg_ipv4_add: sent pulse (0) to flush IARM IPv4 registration batch. Sep 13 08:18:32.014 mpls_te/link 0/RP0/CPU0 t4491 Configured link (0x20) with classic affinity: value 0x1 Sep 13 08:18:32.014 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry Sep 13 08:18:32.014 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/2 (ifh 0x28) into db based on name and handle Sep 13 08:18:32.015 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x28), flood 0, force 0, Nbr cnt 0 Sep 13 08:18:32.015 mpls_te/link 0/RP0/CPU0 t4491 RSI: Registering interface GigabitEthernet0/0/0/2 (ifhndl 0x28) (0x28) for SRLG Notification Sep 13 08:18:32.015 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/2 (ifh 0x28) Created [2 links total], enable 0 Sep 13 08:18:32.015 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x28) BW attribute 0 kbps Sep 13 08:18:32.015 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x28) physical_bw 125000000 Bps Sep 13 08:18:32.018 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) max_bw 100000 (Kbps), pool0_bw 100000 (Kbps) Sep 13 08:18:32.018 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) pool1_bw 0 (Kbps) Sep 13 08:18:32.018 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x28), max_res_bw 100000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.019 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x28), Pool0* max_bw 100000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.019 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x28), max_res_bw 100000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.019 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/2 (ifh 0x28), Pool0* max_bw 100000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.019 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/2 (ifh 0x28) state down Sep 13 08:18:32.022 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/2 (ifh 0x28) to IARM batch Sep 13 08:18:32.022 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1534: mpls_lcac_ifrs_add: Added new IFRS entry Sep 13 08:18:32.022 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1128: rrr_lm_list_link: Inserted link GigabitEthernet0/0/0/3 (ifh 0x30) into db based on name and handle Sep 13 08:18:32.022 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/3 (ifh 0x30), flood 0, force 0, Nbr cnt 0 Sep 13 08:18:32.022 mpls_te/link 0/RP0/CPU0 t4491 RSI: Registering interface GigabitEthernet0/0/0/3 (ifhndl 0x30) (0x30) for SRLG Notification Sep 13 08:18:32.022 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2544: rrr_lm_create_link: link GigabitEthernet0/0/0/3 (ifh 0x30) Created [3 links total], enable 0 Sep 13 08:18:32.023 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/3 (ifh 0x30) BW attribute 0 kbps Sep 13 08:18:32.023 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/3 (ifh 0x30) physical_bw 125000000 Bps Sep 13 08:18:32.026 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:18:32.026 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) pool1_bw 0 (Kbps) Sep 13 08:18:32.026 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/3 (ifh 0x30), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.026 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/3 (ifh 0x30), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.026 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/3 (ifh 0x30), max_res_bw 1000000 (Kbps), Old max_res_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.026 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_set_link_bandwidth(): link GigabitEthernet0/0/0/3 (ifh 0x30), Pool0* max_bw 1000000 (Kbps), Old max_bw 0 (Kbps), IP addr 0.0.0.0 Sep 13 08:18:32.026 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:518: Bandwidth update handling ignored, link GigabitEthernet0/0/0/3 (ifh 0x30) state down Sep 13 08:18:32.108 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/3 (ifh 0x30) to IARM batch Sep 13 08:18:32.108 mpls_te/link 0/RP0/CPU0 t4491 Configured link (0x30) with classic affinity: value 0x2 Sep 13 08:18:32.109 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x20), state 17, proto 0, opcode 35 (CREATE/DEL) Sep 13 08:18:32.109 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x20), intf_type 15, parent i/f None (ifh 0x0), caps 0 Sep 13 08:18:32.109 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/1 (ifh 0x20), state 17, proto 0, opcode 35 Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/1 (ifh 0x20), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x20) BW attribute 0 kbps Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x20) physical_bw 125000000 Bps Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) pool1_bw 0 (Kbps) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) pool1_bw 0 (Kbps) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x20) suppressed, system not ready Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x28), state 17, proto 0, opcode 35 (CREATE/DEL) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x28), intf_type 15, parent i/f None (ifh 0x0), caps 0 Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/2 (ifh 0x28), state 17, proto 0, opcode 35 Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/2 (ifh 0x28), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x28) BW attribute 0 kbps Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x28) physical_bw 125000000 Bps Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) max_bw 100000 (Kbps), pool0_bw 100000 (Kbps) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) pool1_bw 0 (Kbps) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) pool1_bw 0 (Kbps) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x28) suppressed, system not ready Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/3 (ifh 0x30), state 17, proto 0, opcode 35 (CREATE/DEL) Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/3 (ifh 0x30), intf_type 15, parent i/f None (ifh 0x0), caps 0 Sep 13 08:18:32.110 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/3 (ifh 0x30), state 17, proto 0, opcode 35 Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/3 (ifh 0x30), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/3 (ifh 0x30) BW attribute 0 kbps Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/3 (ifh 0x30) physical_bw 125000000 Bps Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) pool1_bw 0 (Kbps) Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) pool1_bw 0 (Kbps) Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5281: Flooding on link GigabitEthernet0/0/0/3 (ifh 0x30) suppressed, system not ready Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 RSI: Synchronous batch handler: 3 items Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 RSI: Event Update with 0 SRLG values for GigabitEthernet0/0/0/1 (ifhndl 0x20) (0x20) [change: NO, old count 0] Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 RSI: Event Update with 0 SRLG values for GigabitEthernet0/0/0/2 (ifhndl 0x28) (0x28) [change: NO, old count 0] Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 RSI: Event Update with 1 SRLG values for GigabitEthernet0/0/0/3 (ifhndl 0x30) (0x30) [change: YES, old count 0] Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 RSI: Deleting all 0 SRLG values for GigabitEthernet0/0/0/3 (ifhndl 0x30) (0x30) Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 RSI: Adding all 1 SRLG values to GigabitEthernet0/0/0/3 (ifhndl 0x30) (0x30) Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 RSI: Added SRLG value 100 to GigabitEthernet0/0/0/3 (ifhndl 0x30) (0x30) => [Total SRLGs for link: 1] Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 RSI: Checkpointing 1 SRLG values for GigabitEthernet0/0/0/3 (ifhndl 0x30) (0x30) Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5327: Periodic Flooding request canceled: flooding disabled Sep 13 08:18:32.111 mpls_te/link 0/RP0/CPU0 t4491 RSI: Sent registration for 3 interfaces (Success 3, failure 0) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_im_attr_capacity_handler: link MgmtEth0/RP0/CPU0/0 (ifh 0x10),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/0 (ifh 0x18),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/1 (ifh 0x20),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_process_link_capacity(): capacity not changed link GigabitEthernet0/0/0/1 (ifh 0x20), current bw 125000000 (Kbps), new bw 125000000 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) pool1_bw 0 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) pool1_bw 0 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/2 (ifh 0x28),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_process_link_capacity(): capacity not changed link GigabitEthernet0/0/0/2 (ifh 0x28), current bw 125000000 (Kbps), new bw 125000000 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) max_bw 100000 (Kbps), pool0_bw 100000 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) pool1_bw 0 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) pool1_bw 0 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_im_attr_capacity_handler: link GigabitEthernet0/0/0/3 (ifh 0x30),bw 1000000 (kbps), bw2 125000000 (Bps), event 0 Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_process_link_capacity(): capacity not changed link GigabitEthernet0/0/0/3 (ifh 0x30), current bw 125000000 (Kbps), new bw 125000000 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) pool1_bw 0 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) pool1_bw 0 (Kbps) Sep 13 08:18:32.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:279: lm_iarm_handle_pulse: received pulse (0), flushing IARM IPv4 registration batch. Sep 13 08:18:32.113 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:623: lm_iarm_flush: sent 3 IPv4 (un)register requests to IARM. Sep 13 08:18:32.716 mpls_te/link 0/RP0/CPU0 t4491 SRLG-producer connected Sep 13 08:18:32.719 mpls_te/link 0/RP0/CPU0 t4491 RSI SRLG-producer registration done successfully Sep 13 08:18:32.719 mpls_te/link 0/RP0/CPU0 t4491 Replaying learned SRLGs on all links to RSI Sep 13 08:18:32.719 mpls_te/link 0/RP0/CPU0 t4491 Replaying learned SRLGs on all termination interfaces to RSI Sep 13 08:18:32.926 mpls_te/link 0/RP0/CPU0 t4491 Validating ifindexes for all links Sep 13 08:18:32.926 mpls_te/link 0/RP0/CPU0 t4491 Bulk ifindex lookup for 3 links Sep 13 08:18:32.928 mpls_te/link 0/RP0/CPU0 t4491 Link GigabitEthernet0/0/0/1 (ifhndl 0x20) (0x20) ifindex set to 5 Sep 13 08:18:32.928 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x20), flood 0, force 1, Nbr cnt 0 Sep 13 08:18:32.928 mpls_te/link 0/RP0/CPU0 t4491 Link GigabitEthernet0/0/0/2 (ifhndl 0x28) (0x28) ifindex set to 6 Sep 13 08:18:32.928 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x28), flood 0, force 1, Nbr cnt 0 Sep 13 08:18:32.928 mpls_te/link 0/RP0/CPU0 t4491 Link GigabitEthernet0/0/0/3 (ifhndl 0x30) (0x30) ifindex set to 7 Sep 13 08:18:32.928 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/3 (ifh 0x30), flood 0, force 1, Nbr cnt 0 Sep 13 08:18:35.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:119: Unable to process adj-change from IGP OSPFarea 0 on link Loopback0 (ifh 0x14) with handle 0x14, reverting to name Sep 13 08:18:35.112 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:145: Unable to process adj-change from IGP OSPF area 0 on link Loopback0 (ifh 0x14) (0 nbrs): link not found Sep 13 08:18:37.410 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2611: Handling area change: IGP OSPF area 0, is_up = 1, router-id 2.2.2.2 Sep 13 08:18:37.410 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x20), flood 0, force 0, Nbr cnt 0 Sep 13 08:18:37.410 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x28), flood 0, force 0, Nbr cnt 0 Sep 13 08:18:37.410 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/3 (ifh 0x30), flood 0, force 0, Nbr cnt 0 Sep 13 08:18:37.411 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5406: Periodic Flooding for igp-type: 2 area: 0 0 seconds Sep 13 08:18:37.411 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5214: System change: flooding to all links/areas: reason area state change Sep 13 08:18:40.420 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x20), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 13 08:18:40.420 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x20), intf_type 15, parent i/f None (ifh 0x0), caps 26 Sep 13 08:18:40.420 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/1 (ifh 0x20), state 17, proto 12, opcode 35 Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/1 (ifh 0x20), add True Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/1 (ifh 0x20) to IARM batch Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:756: lm_iarm_reg_ipv4_add: sent pulse (0) to flush IARM IPv4 registration batch. Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/1 (ifh 0x20), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/1 (ifh 0x20) BW attribute 0 kbps Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/1 (ifh 0x20) physical_bw 125000000 Bps Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) pool1_bw 0 (Kbps) Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/1 (ifh 0x20) pool1_bw 0 (Kbps) Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x20) suppressed, no change Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x28), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x28), intf_type 15, parent i/f None (ifh 0x0), caps 26 Sep 13 08:18:40.422 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/2 (ifh 0x28), state 17, proto 12, opcode 35 Sep 13 08:18:40.423 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/2 (ifh 0x28), add True Sep 13 08:18:40.423 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/2 (ifh 0x28) to IARM batch Sep 13 08:18:40.423 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/2 (ifh 0x28), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/2 (ifh 0x28) BW attribute 0 kbps Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/2 (ifh 0x28) physical_bw 125000000 Bps Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) max_bw 100000 (Kbps), pool0_bw 100000 (Kbps) Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) pool1_bw 0 (Kbps) Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/2 (ifh 0x28) pool1_bw 0 (Kbps) Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x28) suppressed, no change Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3722: lm_im_handler: link GigabitEthernet0/0/0/3 (ifh 0x30), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3727: lm_im_handler: link GigabitEthernet0/0/0/3 (ifh 0x30), intf_type 15, parent i/f None (ifh 0x0), caps 26 Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3808: lm_im_handler: IM_CREATE: link GigabitEthernet0/0/0/3 (ifh 0x30), state 17, proto 12, opcode 35 Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:875: mpls_lcac_if_state_register: link GigabitEthernet0/0/0/3 (ifh 0x30), add True Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:741: lm_iarm_reg_ipv4_add: added IPv4 register(True) request for interface GigabitEthernet0/0/0/3 (ifh 0x30) to IARM batch Sep 13 08:18:40.424 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3881: lm_im_handler: Updating link GigabitEthernet0/0/0/3 (ifh 0x30), intf_type 15, data_parent i/f None (ifh 0x0) (IM_CREATE) Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_idb_capacity: link GigabitEthernet0/0/0/3 (ifh 0x30) BW attribute 0 kbps Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_link_capacity: link GigabitEthernet0/0/0/3 (ifh 0x30) physical_bw 125000000 Bps Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) max_bw 1000000 (Kbps), pool0_bw 1000000 (Kbps) Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_rdm_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) pool1_bw 0 (Kbps) Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) max_bw 0 (Kbps), pool0_bw 0 (Kbps) Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: mpls_lcac_cfg_int_bandwidth_mam_apply: link GigabitEthernet0/0/0/3 (ifh 0x30) pool1_bw 0 (Kbps) Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5304: Flooding on link GigabitEthernet0/0/0/3 (ifh 0x30) suppressed, no change Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x20), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x20), state 1, proto 12, opcode 37 Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x28), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x28), state 1, proto 12, opcode 37 Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/3 (ifh 0x30), state 1, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/3 (ifh 0x30), state 1, proto 12, opcode 37 Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:279: lm_iarm_handle_pulse: received pulse (0), flushing IARM IPv4 registration batch. Sep 13 08:18:40.425 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:623: lm_iarm_flush: sent 3 IPv4 (un)register requests to IARM. Sep 13 08:18:40.511 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/1 (ifh 0x20), ct=0x0, ps=0x0, addr 10.2.3.2 Sep 13 08:18:40.512 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/2 (ifh 0x28), ct=0x0, ps=0x0, addr 10.2.5.2 Sep 13 08:18:40.512 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:797: lm_ipv4_addr_callback: link GigabitEthernet0/0/0/3 (ifh 0x30), ct=0x0, ps=0x0, addr 10.2.7.2 Sep 13 08:18:40.806 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x20), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:18:40.806 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x20), state 2, proto 12, opcode 37 Sep 13 08:18:40.806 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x28), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:18:40.806 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x28), state 2, proto 12, opcode 37 Sep 13 08:18:40.806 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/3 (ifh 0x30), state 2, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:18:40.806 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/3 (ifh 0x30), state 2, proto 12, opcode 37 Sep 13 08:18:41.106 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/1 (ifh 0x20), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:18:41.106 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/1 (ifh 0x20), state 3, proto 12, opcode 37 Sep 13 08:18:41.106 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/1 (ifh 0x20), flood 0, force 0, Nbr cnt 0 Sep 13 08:18:41.106 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/1 (ifh 0x20), area 0, IGP OSPF Sep 13 08:18:41.116 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/2 (ifh 0x28), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:18:41.116 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/2 (ifh 0x28), state 3, proto 12, opcode 37 Sep 13 08:18:41.116 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/2 (ifh 0x28), flood 0, force 0, Nbr cnt 0 Sep 13 08:18:41.116 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/2 (ifh 0x28), area 0, IGP OSPF Sep 13 08:18:41.206 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3700: lm_im_handler: link GigabitEthernet0/0/0/3 (ifh 0x30), state 3, proto 12, caps 26, opcode=STATE_VALUE Sep 13 08:18:41.206 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3765: lm_im_handler: IFSTATE_VAL: link GigabitEthernet0/0/0/3 (ifh 0x30), state 3, proto 12, opcode 37 Sep 13 08:18:41.206 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5569: rrr_lm_update_link_neighbors(): link GigabitEthernet0/0/0/3 (ifh 0x30), flood 0, force 0, Nbr cnt 0 Sep 13 08:18:41.207 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5494: Request link adjacencies for link GigabitEthernet0/0/0/3 (ifh 0x30), area 0, IGP OSPF Sep 13 08:18:41.210 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x20) (0 nbrs) in area 0, IGP OSPF Sep 13 08:18:41.210 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x20), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:18:41.210 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x20) new igp admin weight 30, prev. isis wt 0, ospf wt 0 Sep 13 08:18:41.210 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:935: Link GigabitEthernet0/0/0/1 (ifh 0x20): Added area 0 IGP OSPF Sep 13 08:18:41.210 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1019: Link GigabitEthernet0/0/0/1 (ifh 0x20): Update nbrs for area 0 IGP OSPF Sep 13 08:18:41.210 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/1 (ifh 0x20), area 0, protocol OSPF, weight 30 Sep 13 08:18:41.210 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2523: link GigabitEthernet0/0/0/1 (ifh 0x20), IGP OSPF area 0: no neighbor changes Sep 13 08:18:41.210 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x20) data changed Sep 13 08:18:41.212 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x28) (0 nbrs) in area 0, IGP OSPF Sep 13 08:18:41.212 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/2 (ifh 0x28), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:18:41.212 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x28) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 13 08:18:41.212 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:935: Link GigabitEthernet0/0/0/2 (ifh 0x28): Added area 0 IGP OSPF Sep 13 08:18:41.212 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1019: Link GigabitEthernet0/0/0/2 (ifh 0x28): Update nbrs for area 0 IGP OSPF Sep 13 08:18:41.212 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/2 (ifh 0x28), area 0, protocol OSPF, weight 10 Sep 13 08:18:41.213 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2523: link GigabitEthernet0/0/0/2 (ifh 0x28), IGP OSPF area 0: no neighbor changes Sep 13 08:18:41.213 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x28) data changed Sep 13 08:18:41.217 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/3 (ifh 0x30) (0 nbrs) in area 0, IGP OSPF Sep 13 08:18:41.217 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/3 (ifh 0x30), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:18:41.217 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/3 (ifh 0x30) new igp admin weight 20, prev. isis wt 0, ospf wt 0 Sep 13 08:18:41.217 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:935: Link GigabitEthernet0/0/0/3 (ifh 0x30): Added area 0 IGP OSPF Sep 13 08:18:41.217 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1019: Link GigabitEthernet0/0/0/3 (ifh 0x30): Update nbrs for area 0 IGP OSPF Sep 13 08:18:41.217 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1345: setting IGP cost for link GigabitEthernet0/0/0/3 (ifh 0x30), area 0, protocol OSPF, weight 20 Sep 13 08:18:41.217 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2523: link GigabitEthernet0/0/0/3 (ifh 0x30), IGP OSPF area 0: no neighbor changes Sep 13 08:18:41.217 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/3 (ifh 0x30) data changed Sep 13 08:18:41.217 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x20) (0 nbrs) in area 0, IGP OSPF Sep 13 08:18:41.217 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x20), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:18:41.217 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x20) new igp admin weight 30, prev. isis wt 0, ospf wt 0 Sep 13 08:18:41.218 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2523: link GigabitEthernet0/0/0/1 (ifh 0x20), IGP OSPF area 0: no neighbor changes Sep 13 08:18:41.218 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x28) (0 nbrs) in area 0, IGP OSPF Sep 13 08:18:41.218 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/2 (ifh 0x28), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:18:41.218 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x28) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 13 08:18:41.218 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2523: link GigabitEthernet0/0/0/2 (ifh 0x28), IGP OSPF area 0: no neighbor changes Sep 13 08:18:41.218 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/3 (ifh 0x30) (0 nbrs) in area 0, IGP OSPF Sep 13 08:18:41.218 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/3 (ifh 0x30), tot_nbrs 0, subnet type 0, flags 0x2 Sep 13 08:18:41.218 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/3 (ifh 0x30) new igp admin weight 20, prev. isis wt 0, ospf wt 0 Sep 13 08:18:41.218 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2523: link GigabitEthernet0/0/0/3 (ifh 0x30), IGP OSPF area 0: no neighbor changes Sep 13 08:18:41.512 mpls_te/link 0/RP0/CPU0 t4491 Validating ifindexes for all links Sep 13 08:18:42.811 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/3 (ifh 0x30) (0 nbrs) in area 0, IGP OSPF Sep 13 08:18:42.811 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/3 (ifh 0x30), tot_nbrs 0, subnet type 1, flags 0xb Sep 13 08:18:42.811 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1019: Link GigabitEthernet0/0/0/3 (ifh 0x30): Update nbrs for area 0 IGP OSPF Sep 13 08:18:42.811 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/3 (ifh 0x30), area 0, protocol 2, subnet type 1 Sep 13 08:18:42.811 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/3 (ifh 0x30) new igp admin weight 20, prev. isis wt 0, ospf wt 0 Sep 13 08:18:42.811 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2498: Link GigabitEthernet0/0/0/3 (ifh 0x30): update DB for 1 neighbors Sep 13 08:18:42.811 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1559: Received neighbor 10.2.7.7 on link GigabitEthernet0/0/0/3 (ifh 0x30), IGP OSPF area 0 Sep 13 08:18:42.811 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1578: Rcvd nbr node ID 7.7.7.7, state 1 Sep 13 08:18:42.811 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1596: Matching existing neighbor found? False Sep 13 08:18:42.811 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1714: Neighbor unknown: creating new one Sep 13 08:18:42.812 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1286: link GigabitEthernet0/0/0/3 (ifh 0x30): setting IGP cost: 20, in area 0, protocol OSPF Sep 13 08:18:42.812 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1429: link GigabitEthernet0/0/0/3 (ifh 0x30), IGP OSPF area 0: subnet type changed to 1 Sep 13 08:18:42.812 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/3 (ifh 0x30) nbr addr 10.2.7.7, count 1, nbr state 1 Sep 13 08:18:42.812 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/3 (ifh 0x30) data changed Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/2 (ifh 0x28) (0 nbrs) in area 0, IGP OSPF Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/2 (ifh 0x28), tot_nbrs 0, subnet type 1, flags 0xb Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1019: Link GigabitEthernet0/0/0/2 (ifh 0x28): Update nbrs for area 0 IGP OSPF Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/2 (ifh 0x28), area 0, protocol 2, subnet type 1 Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/2 (ifh 0x28) new igp admin weight 10, prev. isis wt 0, ospf wt 0 Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2498: Link GigabitEthernet0/0/0/2 (ifh 0x28): update DB for 1 neighbors Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1559: Received neighbor 10.2.5.5 on link GigabitEthernet0/0/0/2 (ifh 0x28), IGP OSPF area 0 Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1578: Rcvd nbr node ID 5.5.5.5, state 1 Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1596: Matching existing neighbor found? False Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1714: Neighbor unknown: creating new one Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1286: link GigabitEthernet0/0/0/2 (ifh 0x28): setting IGP cost: 10, in area 0, protocol OSPF Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1429: link GigabitEthernet0/0/0/2 (ifh 0x28), IGP OSPF area 0: subnet type changed to 1 Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/2 (ifh 0x28) nbr addr 10.2.5.5, count 2, nbr state 1 Sep 13 08:18:42.885 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/2 (ifh 0x28) data changed Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2565: Processing adj-change for link GigabitEthernet0/0/0/1 (ifh 0x20) (0 nbrs) in area 0, IGP OSPF Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2451: rrr_lm_handle_igp_link_info_change: OSPF- link GigabitEthernet0/0/0/1 (ifh 0x20), tot_nbrs 0, subnet type 1, flags 0xb Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1019: Link GigabitEthernet0/0/0/1 (ifh 0x20): Update nbrs for area 0 IGP OSPF Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1515: setting subnet type for link GigabitEthernet0/0/0/1 (ifh 0x20), area 0, protocol 2, subnet type 1 Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2471: rrr_lm_handle_igp_link_info_change: link GigabitEthernet0/0/0/1 (ifh 0x20) new igp admin weight 30, prev. isis wt 0, ospf wt 0 Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:2498: Link GigabitEthernet0/0/0/1 (ifh 0x20): update DB for 1 neighbors Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1559: Received neighbor 10.2.3.3 on link GigabitEthernet0/0/0/1 (ifh 0x20), IGP OSPF area 0 Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1578: Rcvd nbr node ID 3.3.3.3, state 1 Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1596: Matching existing neighbor found? False Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1714: Neighbor unknown: creating new one Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1286: link GigabitEthernet0/0/0/1 (ifh 0x20): setting IGP cost: 30, in area 0, protocol OSPF Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1429: link GigabitEthernet0/0/0/1 (ifh 0x20), IGP OSPF area 0: subnet type changed to 1 Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:1825: Neighbor created link GigabitEthernet0/0/0/1 (ifh 0x20) nbr addr 10.2.3.3, count 3, nbr state 1 Sep 13 08:18:48.371 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5295: Flooding on link GigabitEthernet0/0/0/1 (ifh 0x20) data changed Sep 13 08:21:32.309 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:5471: Forced Flooding for IGP OSPF, area 0 Sep 13 08:29:49.923 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK: rrr_lm_im_attr_capacity_handler: link tunnel-te0 (ifh 0x1c),bw 0 (kbps), bw2 0 (Bps), event 0 Sep 13 08:29:49.924 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3722: lm_im_handler: link tunnel-te0 (ifh 0x1c), state 17, proto 12, opcode 35 (CREATE/DEL) Sep 13 08:29:49.924 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3727: lm_im_handler: link tunnel-te0 (ifh 0x1c), intf_type 36, parent i/f None (ifh 0x0), caps 26 Sep 13 08:29:49.924 mpls_te/link 0/RP0/CPU0 t4491 LM_LINK:3808: lm_im_handler: IM_CREATE: link tunnel-te0 (ifh 0x1c), state 17, proto 12, opcode 35 RP/0/RP0/CPU0:PE1#show rsvp trace signalling Sun Sep 13 08:58:56.639 UTC 15 wrapping entries (264256 possible, 64 allocated, 0 filtered, 15 total) Sep 13 08:29:50.128 rsvp/sig 0/RP0/CPU0 t4338 SIG:4545: PATH creating head: local/nbor/nhop (2.2.2.2/7.7.7.7/10.2.7.7), GigabitEthernet0/0/0/3 (ifh 0x30), psbs: 0, rsbs: 0 Sep 13 08:29:50.128 rsvp/sig 0/RP0/CPU0 t4338 SIG:2836: PATH outgoing creating: flags:0xc0000005, local_rid:2.2.2.2, nbor:7.7.7.7, nhop:10.2.7.7, next_ifh:GigabitEthernet0/0/0/3 (ifh 0x30), obj_len: 0 Sep 13 08:29:50.128 rsvp/sig 0/RP0/CPU0 t4338 SIG:887: PATH Tunnel IPv4 outgoing: dst (9.9.9.9:0), src (2.2.2.2:2) Sep 13 08:29:50.131 rsvp/sig 0/RP0/CPU0 t4338 SIG:721: PATH Tunnel IPv4 created: dst (9.9.9.9:0), src (2.2.2.2:2) Sep 13 08:29:50.246 rsvp/sig 0/RP0/CPU0 t4338 SIG:4654: RESV creating network: GigabitEthernet0/0/0/3 (ifh 0x30), in GigabitEthernet0/0/0/3 (ifh 0x30), obj len: 140, IP src: 10.2.7.7, psbs: 1, rsbs: 0 Sep 13 08:29:50.247 rsvp/sig 0/RP0/CPU0 t4338 TC:276: Bandwidth allocation: GigabitEthernet0/0/0/3 (ifh 0x30), current (0 Bps), port 0, src 2.2.2.2 id 2 Sep 13 08:29:50.247 rsvp/sig 0/RP0/CPU0 t4338 SIG:2881: RESV outgoing creating: rsb flags: 0xc0000011, psb flags: 0xc0000004, local/nbor 2.2.2.2/0.0.0.0, obj len: 0x8c, wedged 0 Sep 13 08:29:50.247 rsvp/sig 0/RP0/CPU0 t4338 SIG:362: RESV Tunnel IPv4 created: dst (9.9.9.9:0), src (2.2.2.2:2) Sep 13 08:29:50.261 rsvp/sig 0/RP0/CPU0 t4338 SIG:492: Assigned backup None (ifh 0x0): dst (9.9.9.9:0), src (2.2.2.2:2) Sep 13 08:48:55.351 rsvp/sig 0/RP0/CPU0 t4338 SIG:4549: PATH creating network: GigabitEthernet0/0/0/3 (ifh 0x30), obj len: 228, IP src: 10.2.7.7 IP dst: 10.2.7.2, psbs: 0, rsbs: 0 Sep 13 08:48:55.352 rsvp/sig 0/RP0/CPU0 t4338 SIG:721: PATH Tunnel IPv4 created: dst (2.2.2.2:0), src (9.9.9.9:2) Sep 13 08:48:55.359 rsvp/sig 0/RP0/CPU0 t4338 SIG:4649: RESV creating tail: local/nbor (2.2.2.2/7.7.7.7), obj len: 80, None (ifh 0x0), psbs: 1, rsbs: 0 Sep 13 08:48:55.359 rsvp/sig 0/RP0/CPU0 t4338 SIG:2881: RESV outgoing creating: rsb flags: 0xd0000005, psb flags: 0xc0000018, local/nbor 2.2.2.2/7.7.7.7, obj len: 0x50, wedged 0 Sep 13 08:48:55.359 rsvp/sig 0/RP0/CPU0 t4338 SIG:528: RESV Tunnel IPv4 outgoing: dst (2.2.2.2:0), src (9.9.9.9:2) Sep 13 08:48:55.359 rsvp/sig 0/RP0/CPU0 t4338 SIG:362: RESV Tunnel IPv4 created: dst (2.2.2.2:0), src (9.9.9.9:2)