RP/0/RP0/CPU0:PE2#show mpls ldp trace peer Sun Oct 11 23:57:32.218 UTC RP/0/RP0/CPU0:PE2#show mpls ldp trace binding Sun Oct 11 23:57:32.324 UTC 37 wrapping entries (1027456 possible, 2688 allocated, 379 filtered, 416 total) Oct 11 22:43:35.455 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:106: VRF(0x60000000): Peer(4.4.4.4:0): Advertise address 5.5.5.5 Oct 11 22:43:35.455 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:106: VRF(0x60000000): Peer(4.4.4.4:0): Advertise address 5.5.5.5 Oct 11 22:43:35.455 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:106: VRF(0x60000000): Peer(4.4.4.4:0): Advertise address 10.4.5.5 Oct 11 22:43:35.455 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:106: VRF(0x60000000): Peer(4.4.4.4:0): Advertise address 10.4.5.5 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:136: VRF(0x60000000): Peer(4.4.4.4:0): Bind address 4.4.4.4 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:136: VRF(0x60000000): Peer(4.4.4.4:0): Bind address 10.3.4.4 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:136: VRF(0x60000000): Peer(4.4.4.4:0): Bind address 10.4.5.4 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:161: VRF(0x60000000): Prefix(4.4.4.4/32): Created, reason 2 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:279: VRF(0x60000000): Prefix(4.4.4.4/32): Add label 3 from peer 4.4.4.4:0 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:161: VRF(0x60000000): Prefix(10.3.4.0/24): Created, reason 2 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:279: VRF(0x60000000): Prefix(10.3.4.0/24): Add label 3 from peer 4.4.4.4:0 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:161: VRF(0x60000000): Prefix(10.4.5.0/24): Created, reason 2 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:279: VRF(0x60000000): Prefix(10.4.5.0/24): Add label 3 from peer 4.4.4.4:0 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:161: VRF(0x60000000): Prefix(2.2.2.2/32): Created, reason 2 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:279: VRF(0x60000000): Prefix(2.2.2.2/32): Add label 44000 from peer 4.4.4.4:0 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:161: VRF(0x60000000): Prefix(3.3.3.3/32): Created, reason 2 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:279: VRF(0x60000000): Prefix(3.3.3.3/32): Add label 44001 from peer 4.4.4.4:0 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:161: VRF(0x60000000): Prefix(10.2.3.0/24): Created, reason 2 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:279: VRF(0x60000000): Prefix(10.2.3.0/24): Add label 44002 from peer 4.4.4.4:0 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:161: VRF(0x60000000): Prefix(5.5.5.5/32): Created, reason 2 Oct 11 22:43:35.462 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:279: VRF(0x60000000): Prefix(5.5.5.5/32): Add label 44003 from peer 4.4.4.4:0 Oct 11 22:43:36.919 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:2362: tc_enqueue_peer_update_all_work; AF=IPv4 Oct 11 22:43:39.253 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:221: VRF(0x60000000): Prefix(5.5.5.5/32): label 3 (rev#8) assigned Oct 11 22:43:39.253 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:221: VRF(0x60000000): Prefix(10.4.5.0/24): label 3 (rev#9) assigned Oct 11 22:43:39.254 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:221: VRF(0x60000000): Prefix(2.2.2.2/32): label 55000 (rev#10) assigned Oct 11 22:43:39.254 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:221: VRF(0x60000000): Prefix(3.3.3.3/32): label 55001 (rev#11) assigned Oct 11 22:43:39.255 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:221: VRF(0x60000000): Prefix(4.4.4.4/32): label 55002 (rev#12) assigned Oct 11 22:43:39.255 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:221: VRF(0x60000000): Prefix(10.2.3.0/24): label 55003 (rev#13) assigned Oct 11 22:43:39.255 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:221: VRF(0x60000000): Prefix(10.3.4.0/24): label 55004 (rev#14) assigned Oct 11 22:43:46.919 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:2362: tc_enqueue_peer_update_all_work; AF=IPv4 Oct 11 22:43:46.920 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:76: VRF(0x60000000): Peer(4.4.4.4:0): Advertise label 3 (rev#8) Oct 11 22:43:46.920 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:76: VRF(0x60000000): Peer(4.4.4.4:0): Advertise label 3 (rev#9) Oct 11 22:43:46.920 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:76: VRF(0x60000000): Peer(4.4.4.4:0): Advertise label 55000 (rev#10) Oct 11 22:43:46.920 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:76: VRF(0x60000000): Peer(4.4.4.4:0): Advertise label 55001 (rev#11) Oct 11 22:43:46.920 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:76: VRF(0x60000000): Peer(4.4.4.4:0): Advertise label 55002 (rev#12) Oct 11 22:43:46.920 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:76: VRF(0x60000000): Peer(4.4.4.4:0): Advertise label 55003 (rev#13) Oct 11 22:43:46.920 mpls/ldp/bndg 0/RP0/CPU0 t4150 [BNDG]:76: VRF(0x60000000): Peer(4.4.4.4:0): Advertise label 55004 (rev#14) RP/0/RP0/CPU0:PE2#show mpls lsd trace Sun Oct 11 23:57:32.470 UTC 203 wrapping entries (224896 possible, 896 allocated, 0 filtered, 203 total) Oct 11 22:41:15.415 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:431: ************ MPLS-LSD started ********** Oct 11 22:41:15.415 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:446: Successfully initialized module 'base' Oct 11 22:41:15.415 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:446: Successfully initialized module 'app_mgr' Oct 11 22:41:15.415 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:446: Successfully initialized module 'bcdl2' Oct 11 22:41:15.415 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:446: Successfully initialized module 'im' Oct 11 22:41:15.417 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:446: Successfully initialized module 'rw mgr' Oct 11 22:41:15.417 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:446: Successfully initialized module 'lbl_mgr' Oct 11 22:41:15.418 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:446: Successfully initialized module 'ul_mgr' Oct 11 22:41:15.418 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:446: Successfully initialized module 'edm_fwd' Oct 11 22:41:15.418 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:446: Successfully initialized module 'lsr_mib_edm' Oct 11 22:41:15.513 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:399: Node mem init:state='Normal', 255626332K phy, 242606136K free Oct 11 22:41:15.513 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:527: Successfully initialized module 'oom init' Oct 11 22:41:15.513 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:527: Successfully initialized module 'base preinit' Oct 11 22:41:17.613 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:527: Successfully initialized module 'dbg init' Oct 11 22:41:17.613 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:527: Successfully initialized module 'im preinit' Oct 11 22:41:17.613 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:527: Successfully initialized module 'lbl mgr preinit' Oct 11 22:41:17.714 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:967: Registered chkpt table (App) Oct 11 22:41:17.714 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:982: Registered chkpt table (chkpt-register) Oct 11 22:41:17.714 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:995: Registered chkpt table (Lbl) Oct 11 22:41:17.715 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:1019: Registered chkpt table (SRLB Range) Oct 11 22:41:17.715 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:527: Successfully initialized module 'chkpt' Oct 11 22:41:18.316 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:527: Successfully initialized module 'sysdb' Oct 11 22:41:18.316 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:527: Successfully initialized module 'ul mgr preinit' Oct 11 22:41:18.316 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:527: Successfully initialized module 'edm rsi preinit' Oct 11 22:41:19.315 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:527: Successfully initialized module 'rcmd init' Oct 11 22:41:19.315 mpls/lsd/rare 0/RP0/CPU0 t2227 PROC:87: SYSMGR role change nfn. New LSD Node role = 1 Oct 11 22:41:19.316 mpls/lsd/proc 0/RP0/CPU0 t1996 PROC:221: Successful lsd_pm_init exit Oct 11 22:41:19.414 mpls/lsd/proc 0/RP0/CPU0 t1996 PROC:94: Successful lsd_pm_conn_cb exit Oct 11 22:41:19.414 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:129: Entered lsd_pm_notify_register_internal: old_issu_role=0 new_issu_role=0 reg_type=7 Oct 11 22:41:19.414 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:129: Entered lsd_pm_notify_register_internal: old_issu_role=0 new_issu_role=1 reg_type=7 Oct 11 22:41:19.511 mpls/lsd/common 0/RP0/CPU0 t2227 AIPC:343: ipc_init successfully Oct 11 22:41:19.811 mpls/lsd/rare 0/RP0/CPU0 t2227 PROC:883: GO ACTIVE Oct 11 22:41:19.815 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:891: sysmgr available sent Oct 11 22:41:20.717 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:719: Successfully initialized module 'agent mgr' Oct 11 22:41:21.012 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:719: Successfully initialized module 'im init' Oct 11 22:41:25.712 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:719: Successfully initialized module 'sysdb' Oct 11 22:41:25.712 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:719: Successfully initialized module 'cc mgr' Oct 11 22:41:25.712 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:719: Successfully initialized module 'app mgr' Oct 11 22:41:26.419 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1791: BCDL-init: BCDL2 created. name:bcdls_ipv4_lsd, bcdlid=4 Oct 11 22:41:27.212 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1791: BCDL-init: BCDL2 created. name:bcdls_ipv6_lsd, bcdlid=5 Oct 11 22:41:27.212 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:719: Successfully initialized module 'bcdl2 int' Oct 11 22:41:27.213 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:719: Successfully initialized module 'encap ID int' Oct 11 22:41:27.213 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:719: Successfully initialized module 'lbl mgr' Oct 11 22:41:27.416 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:719: Successfully initialized module 'lsr mib' Oct 11 22:41:27.417 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:719: Successfully initialized module 'rcc init' Oct 11 22:41:27.417 mpls/lsd/common 0/RP0/CPU0 t2227 CHKPT:908: Chkpt successfully gone active Oct 11 22:41:28.011 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:465: Successfully initialized module 'mpls_rlb_sysdb' Oct 11 22:41:28.011 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:592: Role change[2]:Successfully (un)initialized module: mpls_rlb_sysdb init Oct 11 22:41:28.011 mpls/lsd/rare 0/RP0/CPU0 t2227 CHKPT:825: Entering lsd_chkpt_app_initial_load Oct 11 22:41:28.012 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:845: Done recovery (App), rec_cnt=0, altered=0 Oct 11 22:41:28.012 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:3863: Initial app reg/dnld bitset=0x0 Oct 11 22:41:28.012 mpls/lsd/rare 0/RP0/CPU0 t2227 CHKPT:211: Entering lsd_chkpt_lbl_initial_load Oct 11 22:41:28.012 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:245: Done recovery (Lbl), rec_cnt=0, altered=0 Oct 11 22:41:28.012 mpls/lsd/rare 0/RP0/CPU0 t2227 CHKPT:400: Chkpt label recovery: recovered 0 labels Oct 11 22:41:28.012 mpls/lsd/rare 0/RP0/CPU0 t2227 CHKPT:626: Entering lsd_chkpt_srlb_range_initial_load Oct 11 22:41:28.012 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:643: Done recovery (SRLB Range), rec_cnt=0, altered=0 Oct 11 22:41:28.012 mpls/lsd/id_lib 0/RP0/CPU0 t2227 ID_LIB:462: id lib alloc: id_max=1024576, flags=0 Oct 11 22:41:28.714 mpls/lsd/rare 0/RP0/CPU0 t2227 PROC:965: sysdb scan complete Oct 11 22:41:28.714 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:4116: Starting APP-reg tmr=600s, APP-dnld tmr=1800s Oct 11 22:41:28.714 mpls/lsd/rare 0/RP0/CPU0 t2227 PROC:1005: GO ACTIVE Complete Oct 11 22:41:28.714 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:180: Successful lsd_pm_notify_register_internal exit Oct 11 22:41:28.814 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:151: DS connect cb, status = 0. Oct 11 22:41:28.814 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:115: Encap ID manager initialized Oct 11 22:41:28.815 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1452: LSDv4 - new UG create: UG = 1, Parent UG = -2, UG type:1, flags:0x0, bcdlc_id:0 Oct 11 22:41:28.815 mpls/lsd/rcc 0/RP0/CPU0 t2227 RCC:1217: RCC range UG (index = 0, ug_id = 1, bcdls_id = 4) is created Oct 11 22:41:28.815 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1393: LSDv4 - UG 1 created, flags:0x0 Oct 11 22:41:28.815 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1452: LSDv4 - new UG create: UG = 2, Parent UG = -2, UG type:1, flags:0x0, bcdlc_id:0 Oct 11 22:41:28.815 mpls/lsd/rcc 0/RP0/CPU0 t2227 RCC:1213: RCC backgound UG (index = 1, ug_id = 2, bcdls_id = 4) is created Oct 11 22:41:28.815 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1393: LSDv4 - UG 2 created, flags:0x0 Oct 11 22:41:28.815 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1452: LSDv6 - new UG create: UG = 1, Parent UG = -2, UG type:1, flags:0x0, bcdlc_id:0 Oct 11 22:41:28.815 mpls/lsd/rcc 0/RP0/CPU0 t2227 RCC:1217: RCC range UG (index = 2, ug_id = 1, bcdls_id = 5) is created Oct 11 22:41:28.815 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1393: LSDv6 - UG 1 created, flags:0x0 Oct 11 22:41:28.815 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1452: LSDv6 - new UG create: UG = 2, Parent UG = -2, UG type:1, flags:0x0, bcdlc_id:0 Oct 11 22:41:28.815 mpls/lsd/rcc 0/RP0/CPU0 t2227 RCC:1213: RCC backgound UG (index = 3, ug_id = 2, bcdls_id = 5) is created Oct 11 22:41:28.815 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1393: LSDv6 - UG 2 created, flags:0x0 Oct 11 22:41:48.612 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1452: LSDv4 - new UG create: UG = 0, Parent UG = -2, UG type:1, flags:0x0, bcdlc_id:0 Oct 11 22:41:48.612 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1393: LSDv4 - UG 0 created, flags:0x0 Oct 11 22:41:48.612 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1452: LSDv6 - new UG create: UG = 0, Parent UG = -2, UG type:1, flags:0x0, bcdlc_id:0 Oct 11 22:41:48.612 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:1393: LSDv6 - UG 0 created, flags:0x0 Oct 11 22:41:48.912 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1823: RW Update [0/1] Label FPI (BCDL Pri:0) - Incoming Label:0, ELC:0, rw_flags:0x0 Oct 11 22:41:48.912 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:2708: Poplkup-v4 MOI - Table ID:0xe00fffff, path_flags:0x0 Oct 11 22:41:48.912 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1823: RW Update [0/1] Label FPI (BCDL Pri:0) - Incoming Label:1, ELC:0, rw_flags:0x1 Oct 11 22:41:48.912 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:2708: Poplkup-v4 MOI - Table ID:0x0, path_flags:0x0 Oct 11 22:41:48.912 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1823: RW Update [0/1] Label FPI (BCDL Pri:0) - Incoming Label:13, ELC:0, rw_flags:0x1 Oct 11 22:41:48.912 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:2708: Poplkup-v4 MOI - Table ID:0xe0000000, path_flags:0x0 Oct 11 22:41:48.912 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:721: LSDv4 - For UG 0 Downloaded 3 RWs with BCDL priority 0 Oct 11 22:41:48.912 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:790: LSDv4 - Send RW successful for UG 0, updates=3, size=132, total_downloads=1, EOD=1 Oct 11 22:41:48.912 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:892: LSDv4 - EOD sent to FIB for UG 0, all apps converged Oct 11 22:41:48.912 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:941: LSDv4 - Send RW successful for UG 0, updates=3, size=52, total_downloads=2, EOD=1 Oct 11 22:41:48.912 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1823: RW Update [0/1] Label FPI (BCDL Pri:0) - Incoming Label:2, ELC:0, rw_flags:0x40000 Oct 11 22:41:48.912 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:2710: Poplkup-v6 MOI - Table ID:0xe08fffff, path_flags:0x0 Oct 11 22:41:48.912 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:721: LSDv6 - For UG 0 Downloaded 1 RWs with BCDL priority 0 Oct 11 22:41:48.912 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:790: LSDv6 - Send RW successful for UG 0, updates=1, size=80, total_downloads=1, EOD=1 Oct 11 22:41:48.913 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:892: LSDv6 - EOD sent to FIB for UG 0, all apps converged Oct 11 22:41:48.913 mpls/lsd/rare 0/RP0/CPU0 t2227 RW:528: encap id EOD sent Oct 11 22:41:48.913 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:941: LSDv6 - Send RW successful for UG 0, updates=1, size=52, total_downloads=2, EOD=1 Oct 11 22:41:57.014 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:503: Rx-ed trig msg from agent, nodeid=0x0 Oct 11 22:41:57.014 mpls/lsd/rare 0/RP0/CPU0 t2227 AGENT:550: wrote 0 intfs to node=0x0, len=60 Oct 11 22:43:08.931 mpls/lsd/rare 0/RP0/CPU0 t2227 LBL:248: Label range change notification update: lbl_tbl_id=0, tmask=0x13, dyn=(55000,55999), static=(0,0) Oct 11 22:43:08.931 mpls/lsd/id_lib 0/RP0/CPU0 t2227 ID_LIB:462: id lib alloc: id_max=1000, flags=0 Oct 11 22:43:08.931 mpls/lsd/rare 0/RP0/CPU0 t2227 LBL:5946: Label OOR clr due to label range set Oct 11 22:43:18.920 mpls/lsd/common 0/RP0/CPU0 t2227 AIPC:254: ipc_bind_v2 successfully Oct 11 22:43:18.920 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:565: Publish DS service successfully for endp LSD_DS. Oct 11 22:43:18.920 mpls/lsd/client 0/RP0/CPU0 t4700 :204: client(pid:4632) DS endpt, svc: LSD_DS, ha role: 1, issu id: 0, local issu_id: 0, endpt valid: 1 Oct 11 22:43:18.921 mpls/lsd/client 0/RP0/CPU0 t3341 :204: client(pid:2672) DS endpt, svc: LSD_DS, ha role: 1, issu id: 0, local issu_id: 0, endpt valid: 1 Oct 11 22:43:18.921 mpls/lsd/client 0/RP0/CPU0 t4420 :204: client(pid:4392) DS endpt, svc: LSD_DS, ha role: 1, issu id: 0, local issu_id: 0, endpt valid: 1 Oct 11 22:43:18.921 mpls/lsd/client 0/RP0/CPU0 t4592 :204: client(pid:4150) DS endpt, svc: LSD_DS, ha role: 1, issu id: 0, local issu_id: 0, endpt valid: 1 Oct 11 22:43:18.921 mpls/lsd/client 0/RP0/CPU0 t4286 :204: client(pid:4140) DS endpt, svc: LSD_DS, ha role: 1, issu id: 0, local issu_id: 0, endpt valid: 1 Oct 11 22:43:18.921 mpls/lsd/client 0/RP0/CPU0 t3473 :204: client(pid:2574) DS endpt, svc: LSD_DS, ha role: 1, issu id: 0, local issu_id: 0, endpt valid: 1 Oct 11 22:43:18.921 mpls/lsd/client 0/RP0/CPU0 t3577 :204: client(pid:2582) DS endpt, svc: LSD_DS, ha role: 1, issu id: 0, local issu_id: 0, endpt valid: 1 Oct 11 22:43:18.921 mpls/lsd/client 0/RP0/CPU0 t3625 :204: client(pid:2589) DS endpt, svc: LSD_DS, ha role: 1, issu id: 0, local issu_id: 0, endpt valid: 1 Oct 11 22:43:19.131 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:213: DS publish service cb, return_code:0, status:0. Oct 11 22:43:19.131 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:420: Start 120 secs high priority apps reg timer Oct 11 22:43:19.215 mpls/lsd/client 0/RP0/CPU0 t4700 :884: client(pid:4632) lib event: Connect to act Oct 11 22:43:19.216 mpls/lsd/client 0/RP0/CPU0 t4420 :884: client(pid:4392) lib event: Connect to act Oct 11 22:43:19.216 mpls/lsd/client 0/RP0/CPU0 t3625 :884: client(pid:2589) lib event: Connect to act Oct 11 22:43:19.217 mpls/lsd/client 0/RP0/CPU0 t4286 :884: client(pid:4140) lib event: Connect to act Oct 11 22:43:19.219 mpls/lsd/client 0/RP0/CPU0 t4592 :884: client(pid:4150) lib event: Connect to act Oct 11 22:43:19.220 mpls/lsd/client 0/RP0/CPU0 t3577 :884: client(pid:2582) lib event: Connect to act Oct 11 22:43:19.221 mpls/lsd/client 0/RP0/CPU0 t3473 :884: client(pid:2574) lib event: Connect to act Oct 11 22:43:19.222 mpls/lsd/client 0/RP0/CPU0 t3341 :884: client(pid:2672) lib event: Connect to act Oct 11 22:43:22.414 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:110: ens_write EOK type=7, len=28 Oct 11 22:43:22.414 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:3900: Clearing APP-reg tmr, Last App registered, bitmask=0x0 Oct 11 22:43:22.414 mpls/lsd/str 0/RP0/CPU0 t2227 APP:1266: App created, app=Static::active, flag:0x0, app_idx:1 Oct 11 22:43:22.415 mpls/lsd/client 0/RP0/CPU0 t3341 :997: client(Static::active) lib event: Reg with act Oct 11 22:43:22.415 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:782: Chkpt_save: app, app_idx=1, app_type=3, app_instance:, app_role:1 Oct 11 22:43:38.420 mpls/lsd/rare 0/RP0/CPU0 t2227 APP:1877: App RSRC-COMPLETE, app=Static::active, flag:0x41 Oct 11 22:43:38.420 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:3689: update_list: All apps converged Oct 11 22:43:38.420 mpls/lsd/proc 0/RP0/CPU0 t2227 PROC:403: Start to allow other apps reg, reason: last high prio app rsc complete Oct 11 22:43:38.422 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:892: LSDv4 - EOD sent to FIB for UG 0, all apps converged Oct 11 22:43:38.422 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:941: LSDv4 - Send RW successful for UG 0, updates=0, size=52, total_downloads=3, EOD=1 Oct 11 22:43:38.422 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:892: LSDv6 - EOD sent to FIB for UG 0, all apps converged Oct 11 22:43:38.422 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:941: LSDv6 - Send RW successful for UG 0, updates=0, size=52, total_downloads=3, EOD=1 Oct 11 22:43:38.627 mpls/lsd/client 0/RP0/CPU0 t4420 :997: client(Static:ipv4_static:active) lib event: Reg with act Oct 11 22:43:38.627 mpls/lsd/str 0/RP0/CPU0 t2227 APP:1266: App created, app=Static:ipv4_static:active, flag:0x20, app_idx:2 Oct 11 22:43:38.627 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:782: Chkpt_save: app, app_idx=2, app_type=3, app_instance:ipv4_static, app_role:1 Oct 11 22:43:39.224 mpls/lsd/client 0/RP0/CPU0 t4592 :997: client(LDP::active) lib event: Reg with act Oct 11 22:43:39.224 mpls/lsd/client 0/RP0/CPU0 t4592 :237: client(LDP::active) active LSD notify event:1, flag:0 Oct 11 22:43:39.224 mpls/lsd/str 0/RP0/CPU0 t2227 APP:1266: App created, app=LDP::active, flag:0x20, app_idx:3 Oct 11 22:43:39.224 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:782: Chkpt_save: app, app_idx=3, app_type=4, app_instance:, app_role:1 Oct 11 22:43:39.224 mpls/lsd/rare 0/RP0/CPU0 t2227 AIPC:1928: Notifying app:LDP::active, flag:0x61, event:1. Oct 11 22:43:39.225 mpls/lsd/wrap 0/RP0/CPU0 t2227 IM:523: Enabling intf (0x8), app=LDP::active, flag:0x61, ftypes:0x1 Oct 11 22:43:39.254 mpls/lsd/id_lib 0/RP0/CPU0 t2227 ID_LIB:285: mark_used_in_summary_tree (LSD free ctr 8):id=0, lvl=0, idx=0 bitmap=0x80000000 Oct 11 22:43:39.254 mpls/lsd/id_lib 0/RP0/CPU0 t2227 ID_LIB:260: mark_free_in_summary_tree (LSD free ctr 7):id=0, lvl=1, idx=0 bitmap=0xffffffff Oct 11 22:43:39.254 mpls/lsd/rare 0/RP0/CPU0 t2227 APP:1877: App RSRC-COMPLETE, app=LDP::active, flag:0x61 Oct 11 22:43:39.257 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1899: RW Update [0/2] IPv4 FPI (BCDL Pri:1) - Prefix:2.2.2.2/32, Route flags:0x4000000 Oct 11 22:43:39.257 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1823: RW Update [0/2] Label FPI (BCDL Pri:1) - Incoming Label:55000, ELC:0, rw_flags:0x0 Oct 11 22:43:39.257 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:2794: LFA IPv4-Stack MOI (depth=1) - NH:10.4.5.4, IF:GigabitEthernet0/0/0/0 (ifh 0x8), NHID:0x3, Lbl:44000, path_flags:0x0 Oct 11 22:43:39.257 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1899: RW Update [0/2] IPv4 FPI (BCDL Pri:1) - Prefix:3.3.3.3/32, Route flags:0x4000000 Oct 11 22:43:39.257 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1823: RW Update [0/2] Label FPI (BCDL Pri:1) - Incoming Label:55001, ELC:0, rw_flags:0x0 Oct 11 22:43:39.258 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:2794: LFA IPv4-Stack MOI (depth=1) - NH:10.4.5.4, IF:GigabitEthernet0/0/0/0 (ifh 0x8), NHID:0x3, Lbl:44001, path_flags:0x0 Oct 11 22:43:39.258 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1899: RW Update [0/2] IPv4 FPI (BCDL Pri:1) - Prefix:4.4.4.4/32, Route flags:0x4000000 Oct 11 22:43:39.258 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1823: RW Update [0/2] Label FPI (BCDL Pri:1) - Incoming Label:55002, ELC:0, rw_flags:0x0 Oct 11 22:43:39.258 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:2794: LFA IPv4-Stack MOI (depth=1) - NH:10.4.5.4, IF:GigabitEthernet0/0/0/0 (ifh 0x8), NHID:0x3, Lbl:3, path_flags:0x0 Oct 11 22:43:39.258 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:721: LSDv4 - For UG 0 Downloaded 3 RWs with BCDL priority 1 Oct 11 22:43:39.258 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:790: LSDv4 - Send RW successful for UG 0, updates=3, size=208, total_downloads=4, EOD=0 Oct 11 22:43:39.258 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1899: RW Update [0/2] IPv4 FPI (BCDL Pri:3) - Prefix:10.2.3.0/24, Route flags:0x4000000 Oct 11 22:43:39.258 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1823: RW Update [0/2] Label FPI (BCDL Pri:3) - Incoming Label:55003, ELC:0, rw_flags:0x0 Oct 11 22:43:39.258 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:2794: LFA IPv4-Stack MOI (depth=1) - NH:10.4.5.4, IF:GigabitEthernet0/0/0/0 (ifh 0x8), NHID:0x3, Lbl:44002, path_flags:0x0 Oct 11 22:43:39.258 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1899: RW Update [0/2] IPv4 FPI (BCDL Pri:3) - Prefix:10.3.4.0/24, Route flags:0x4000000 Oct 11 22:43:39.258 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:1823: RW Update [0/2] Label FPI (BCDL Pri:3) - Incoming Label:55004, ELC:0, rw_flags:0x0 Oct 11 22:43:39.258 mpls/lsd/rw 0/RP0/CPU0 t2227 RW:2794: LFA IPv4-Stack MOI (depth=1) - NH:10.4.5.4, IF:GigabitEthernet0/0/0/0 (ifh 0x8), NHID:0x3, Lbl:3, path_flags:0x0 Oct 11 22:43:39.258 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:721: LSDv4 - For UG 0 Downloaded 2 RWs with BCDL priority 3 Oct 11 22:43:39.258 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:790: LSDv4 - Send RW successful for UG 0, updates=2, size=156, total_downloads=5, EOD=1 Oct 11 22:43:39.258 mpls/lsd/bcdl 0/RP0/CPU0 t2227 BCDL:892: LSDv4 - EOD sent to FIB for UG 0, all apps converged Oct 11 22:43:39.258 mpls/lsd/wrap 0/RP0/CPU0 t2227 BCDL:941: LSDv4 - Send RW successful for UG 0, updates=2, size=52, total_downloads=6, EOD=1 Oct 11 22:43:39.520 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:110: ens_write EOK type=7, len=28 Oct 11 22:43:39.520 mpls/lsd/str 0/RP0/CPU0 t2227 APP:1266: App created, app=PIM6:pim6:active, flag:0x20, app_idx:4 Oct 11 22:43:39.520 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:782: Chkpt_save: app, app_idx=4, app_type=14, app_instance:pim6, app_role:1 Oct 11 22:43:39.521 mpls/lsd/client 0/RP0/CPU0 t3577 :997: client(PIM6:pim6:active) lib event: Reg with act Oct 11 22:43:39.721 mpls/lsd/str 0/RP0/CPU0 t2227 APP:1266: App created, app=PIM:pim:active, flag:0x20, app_idx:5 Oct 11 22:43:39.721 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:782: Chkpt_save: app, app_idx=5, app_type=12, app_instance:pim, app_role:1 Oct 11 22:43:39.721 mpls/lsd/client 0/RP0/CPU0 t3625 :997: client(PIM:pim:active) lib event: Reg with act Oct 11 22:43:41.636 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:110: ens_write EOK type=7, len=28 Oct 11 22:43:41.636 mpls/lsd/str 0/RP0/CPU0 t2227 APP:1266: App created, app=Application-Controller:XTC:active, flag:0x20, app_idx:6 Oct 11 22:43:41.636 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:782: Chkpt_save: app, app_idx=6, app_type=6, app_instance:XTC, app_role:1 Oct 11 22:43:41.637 mpls/lsd/client 0/RP0/CPU0 t4286 :997: client(Application-Controller:XTC:active) lib event: Reg with act Oct 11 22:43:43.332 mpls/lsd/str 0/RP0/CPU0 t2227 APP:1266: App created, app=BGP-VPNv4:bgp-default:active, flag:0x20, app_idx:7 Oct 11 22:43:43.332 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:782: Chkpt_save: app, app_idx=7, app_type=8, app_instance:bgp-default, app_role:1 Oct 11 22:43:43.333 mpls/lsd/client 0/RP0/CPU0 t4700 :997: client(BGP-VPNv4:bgp-default:active) lib event: Reg with act Oct 11 22:43:53.635 mpls/lsd/rare 0/RP0/CPU0 t2227 APP:1877: App RSRC-COMPLETE, app=Application-Controller:XTC:active, flag:0x61 Oct 11 22:44:00.627 mpls/lsd/rare 0/RP0/CPU0 t2227 APP:1877: App RSRC-COMPLETE, app=Static:ipv4_static:active, flag:0x61 Oct 11 22:44:16.517 mpls/lsd/str 0/RP0/CPU0 t2227 APP:1266: App created, app=BFD::active, flag:0x20, app_idx:8 Oct 11 22:44:16.517 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:782: Chkpt_save: app, app_idx=8, app_type=11, app_instance:, app_role:1 Oct 11 22:44:16.517 mpls/lsd/client 0/RP0/CPU0 t3473 :997: client(BFD::active) lib event: Reg with act Oct 11 22:44:19.859 mpls/lsd/client 0/RP0/CPU0 t4743 :204: client(pid:4145) DS endpt, svc: LSD_DS, ha role: 1, issu id: 0, local issu_id: 0, endpt valid: 1 Oct 11 22:44:19.861 mpls/lsd/client 0/RP0/CPU0 t4743 :884: client(pid:4145) lib event: Connect to act Oct 11 22:44:20.862 mpls/lsd/str 0/RP0/CPU0 t2227 APP:1266: App created, app=L2VPN::active, flag:0x20, app_idx:9 Oct 11 22:44:20.862 mpls/lsd/str 0/RP0/CPU0 t2227 CHKPT:782: Chkpt_save: app, app_idx=9, app_type=9, app_instance:, app_role:1 Oct 11 22:44:20.862 mpls/lsd/client 0/RP0/CPU0 t4743 :997: client(L2VPN::active) lib event: Reg with act Oct 11 22:44:26.869 mpls/lsd/rare 0/RP0/CPU0 t2227 APP:1877: App RSRC-COMPLETE, app=L2VPN::active, flag:0x61 Oct 11 22:44:37.931 mpls/lsd/rare 0/RP0/CPU0 t2227 APP:1877: App RSRC-COMPLETE, app=BGP-VPNv4:bgp-default:active, flag:0x61 Oct 11 22:45:16.518 mpls/lsd/rare 0/RP0/CPU0 t2227 APP:1877: App RSRC-COMPLETE, app=BFD::active, flag:0x61 Oct 11 22:45:19.814 mpls/lsd/rare 0/RP0/CPU0 t2227 APP:1877: App RSRC-COMPLETE, app=PIM6:pim6:active, flag:0x61 Oct 11 22:45:19.914 mpls/lsd/rare 0/RP0/CPU0 t2227 APP:1877: App RSRC-COMPLETE, app=PIM:pim:active, flag:0x61 Oct 11 23:03:09.029 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:110: ens_write EOK type=5, len=8 Oct 11 23:09:17.530 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:110: ens_write EOK type=5, len=8 Oct 11 23:17:11.411 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:110: ens_write EOK type=5, len=8 Oct 11 23:23:21.964 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:110: ens_write EOK type=5, len=8 Oct 11 23:31:21.657 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:110: ens_write EOK type=5, len=8 Oct 11 23:37:36.616 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:110: ens_write EOK type=5, len=8 Oct 11 23:45:34.336 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:110: ens_write EOK type=5, len=8 Oct 11 23:53:28.233 mpls/lsd/common 0/RP0/CPU0 t2227 AGENT:110: ens_write EOK type=5, len=8