RP/0/RP0/CPU0:Oct 9 10:39:24.535 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP319: MEM Alloc, iox_pak:0xda4ce420 (3662472224) size:297 RP/0/RP0/CPU0:Oct 9 10:39:24.535 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: Allocation request for 82 bytes of memory RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5288: MEM Alloc, DHCPv6-start:0xda4d2440 (3662488640) size:1280 iox_pak:0xda4ce420 (3662472224) RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP320: Received the packet from CLIENT RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP763: PKT IPv6 DHCP: Detailed packet contents RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP762: PKT: src:fe80::c28b:2aff:fe79:4e0 dest:ff02::1:2 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP758: PKT: type: RENEW dhcp->type:5 dhcp->xid:2537967 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:CLIENTID(1) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 00030001c08b2a7904e0 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:SERVERID(2) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 0003000168790973e0a8 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:ELAPSED-TIME(8) optlen:2 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP738: PKT: elaspsed_time:0 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:IA-NA(3) optlen:40 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP748: PKT: IAID 0x0x81a40001 (2175008769) T1:0x0 (0) T2:0x0 (0) RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:IAADDR(5) optlen:24 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP729: PKT: IPV6 addr:101:101::105 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP730: PKT: preferred RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP769: PKT: 120 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP731: PKT: valid RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP733: PKT: len:120 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: dhcpv6d_validate_and_enqueue_msg: msg_type = 5 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP210: dhcpv6d_fill_message_info_structure called RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP214: dhcpv6 message copied to dhcpv6_message_st RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 1 present at 0 offset RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 2 present at 14 offset RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 8 present at 28 offset RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 3 present at 34 offset RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP189: dhcpv6d option ia_na present RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP185: suboption 5 present RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP186: dhcpv6d_opt_ia_na_parse,suboptype 5 at offset 50 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_msg_validate 0 5 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP230: status code = 0, partial stat = 0 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP4514: SUCCESS - IAID validation RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP259: IANA Requested RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP329: Start RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP328: Pre-route pkt on interface: Bundle-Ether101.101 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5327: Pre-route pkt on interface: inner vlan 0x65e0 (26080) outer vlan 0x69e0 (27104) RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP765: PKT IPv6 DHCP: Det pkt cntents RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP762: PKT: src:fe80::6a79:9ff:fe73:e0ad dest:fe80::c28b:2aff:fe79:4e0 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP758: PKT: type: REPLY dhcp->type:7 dhcp->xid:2537967 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:CLIENTID(1) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 00030001c08b2a7904e0 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:SERVERID(2) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 0003000168790973e0a8 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:STATUS-CODE(13) optlen:2 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP740: PKT: status message: RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP739: PKT: Status_code:NO-BINDING RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE EVENT: TP533: Packet sent to client: DUID: 0x00-03-00-01-c0-8b-2a-79-04-e0 IFH:0x16420 (91168) src:fe80::6a79:9ff:fe73:e0ad dst:fe80::c28b:2aff:fe79:4e0 dport:546 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP125: MEM ALLOC - Free, pkt_info:0xda4ce420 (3662472224), size:297 RP/0/RP0/CPU0:Oct 9 10:39:24.536 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5289: MEM ALLOC - Free, DHCPv6-start:0xda4d2440 (3662488640) iox_pak:0xda4ce420 (3662472224) RP/0/RP0/CPU0:Oct 9 10:39:24.538 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP319: MEM Alloc, iox_pak:0xda4ce420 (3662472224) size:297 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: Allocation request for 82 bytes of memory RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5288: MEM Alloc, DHCPv6-start:0xda4d2440 (3662488640) size:1280 iox_pak:0xda4ce420 (3662472224) RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP320: Received the packet from CLIENT RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP763: PKT IPv6 DHCP: Detailed packet contents RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP762: PKT: src:fe80::c28b:2aff:fe79:4e0 dest:ff02::1:2 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP758: PKT: type: REQUEST dhcp->type:3 dhcp->xid:3994986 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:CLIENTID(1) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 00030001c08b2a7904e0 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:SERVERID(2) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 0003000168790973e0a8 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:ELAPSED-TIME(8) optlen:2 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP738: PKT: elaspsed_time:0 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:IA-NA(3) optlen:40 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP748: PKT: IAID 0x0x81a40001 (2175008769) T1:0x0 (0) T2:0x0 (0) RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:IAADDR(5) optlen:24 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP729: PKT: IPV6 addr:101:101::105 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP730: PKT: preferred RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP769: PKT: 120 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP731: PKT: valid RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP733: PKT: len:120 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: dhcpv6d_validate_and_enqueue_msg: msg_type = 3 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP210: dhcpv6d_fill_message_info_structure called RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP214: dhcpv6 message copied to dhcpv6_message_st RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 1 present at 0 offset RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 2 present at 14 offset RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 8 present at 28 offset RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 3 present at 34 offset RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP189: dhcpv6d option ia_na present RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP185: suboption 5 present RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP186: dhcpv6d_opt_ia_na_parse,suboptype 5 at offset 50 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_msg_validate 0 3 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP230: status code = 0, partial stat = 0 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP4514: SUCCESS - IAID validation RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP259: IANA Requested RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP329: Start RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP328: Pre-route pkt on interface: Bundle-Ether101.101 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5327: Pre-route pkt on interface: inner vlan 0x65e0 (26080) outer vlan 0x69e0 (27104) RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP765: PKT IPv6 DHCP: Det pkt cntents RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP762: PKT: src:fe80::6a79:9ff:fe73:e0ad dest:fe80::c28b:2aff:fe79:4e0 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP758: PKT: type: REPLY dhcp->type:7 dhcp->xid:3994986 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:CLIENTID(1) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 00030001c08b2a7904e0 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:SERVERID(2) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 0003000168790973e0a8 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:STATUS-CODE(13) optlen:2 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP740: PKT: status message: RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP739: PKT: Status_code:NO-BINDING RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE EVENT: TP533: Packet sent to client: DUID: 0x00-03-00-01-c0-8b-2a-79-04-e0 IFH:0x16420 (91168) src:fe80::6a79:9ff:fe73:e0ad dst:fe80::c28b:2aff:fe79:4e0 dport:546 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP125: MEM ALLOC - Free, pkt_info:0xda4ce420 (3662472224), size:297 RP/0/RP0/CPU0:Oct 9 10:39:24.539 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5289: MEM ALLOC - Free, DHCPv6-start:0xda4d2440 (3662488640) iox_pak:0xda4ce420 (3662472224) RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP319: MEM Alloc, iox_pak:0xda4ce420 (3662472224) size:297 RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: Allocation request for 40 bytes of memory RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5288: MEM Alloc, DHCPv6-start:0xda4d2440 (3662488640) size:1280 iox_pak:0xda4ce420 (3662472224) RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP320: Received the packet from CLIENT RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP763: PKT IPv6 DHCP: Detailed packet contents RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP762: PKT: src:fe80::c28b:2aff:fe79:4e0 dest:ff02::1:2 RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP758: PKT: type: SOLICIT dhcp->type:1 dhcp->xid:15541272 RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:CLIENTID(1) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 00030001c08b2a7904e0 RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:ELAPSED-TIME(8) optlen:2 RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP738: PKT: elaspsed_time:0 RP/0/RP0/CPU0:Oct 9 10:39:24.541 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:IA-NA(3) optlen:12 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP748: PKT: IAID 0x0x81a40001 (2175008769) T1:0x0 (0) T2:0x0 (0) RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: dhcpv6d_validate_and_enqueue_msg: msg_type = 1 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP210: dhcpv6d_fill_message_info_structure called RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP214: dhcpv6 message copied to dhcpv6_message_st RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 1 present at 0 offset RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 8 present at 14 offset RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 3 present at 20 offset RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP189: dhcpv6d option ia_na present RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_msg_validate 0 1 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP230: status code = 0, partial stat = 0 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP192: option 16 not found RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_is_match_option_filter_drop: profile name: NNI_101_101v6 mode: 1 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP4514: SUCCESS - IAID validation RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP113: Creating Client Entry RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: Adding client to Bundle-Ether101.101 l3intf tree RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: Adding client to Bundle-Ether101.101 interface tree RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP114: Success, client->uid 0x10011e4 (16781796) RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP203: num_ia_id: 1 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP121: Client uid 0x10011e4 (16781796), num_ia 1 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP1737: Reading intf-id from SOLICIT for Client uid 0x10011e4 (16781796), profile NNI_101_101v6 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP122: Enter, Client Uid 0x10011e4 (16781796),ia_id 0x81a40001 (2175008769) client->num_ia_id_pd 1,ia_ptr 0xd9fcfcf0 (3657235696) RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP123: ia_type:0 addr_cnt:1 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP462: timer create: fsm:0xda4cbeb0 (3662462640) ia_id:0x81a40001 (2175008769) ia_type:0 context:0x10011e4 (16781796) uid:0x10011e4 (16781796) RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP369: set_client_ia_info: client:0xda4d2d70 (3662490992) lease:0 ia_id:0x81a40001 (2175008769) ia_type:0 addr_count:1 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP124: MEM ALLOC clone 0xda254fc0 (3659878336), size 0x129 (297) RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: Allocation request for 40 bytes of memory RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5288: MEM Alloc, DHCPv6-start:0xda4d3180 (3662492032) size:1280 iox_pak:0xda254fc0 (3659878336) RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_enqueue_event: after get fsm client-mac=c08b.2a79.04e0 event=1 state=0fsm =0x5600da4cbeb0 ia_type=0 ia_id RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP465: fsm enqueue_event: event:RX_IPOE_SOLICIT_MSG_EVT client-uid:0x10011e4 (16781796) ia_id:0x0x81a40001 (2175008769) ifh:0x0x16420 (91168) mac:c08b.2a79.04e0 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6_enqueue_event_pkt: fsm_event =1 fsm_pkt=0x5600da4c92d0wakeup_scheduler=1 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE EVENT: TP531: Enqueued packet to FSM from intf Bundle-Ether101.101 DUID:0x00-03-00-01-c0-8b-2a-79-04-e0 iaid:0x0x81a40001 (2175008769) iox_pak:0x0xda4ce420 (3662472224) clone:0x0xda254fc0 (3659878336) RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP125: MEM ALLOC - Free, pkt_info:0xda4ce420 (3662472224), size:297 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5289: MEM ALLOC - Free, DHCPv6-start:0xda4d2440 (3662488640) iox_pak:0xda4ce420 (3662472224) RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP421: set_prev_msg: fsm:0xda4cbeb0 (3662462640) iaid:0x81a40001 (2175008769) prev_pkt_info:0x0 (0) curr_pktinfo:0xda254fc0 (3659878336) msgtype:0x1 (1) RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP375: IPoE solicit handler client:0xda4d2d70 (3662490992) fsm:0xda4cbeb0 (3662462640) RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP494: dhcpv6d_dpm_create_ipoe_session call: uid 16781796 chaddr_str c08b.2a79.04e0 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_DEBUG: dhcpv6d_dpm_sir_fc_enabled - Returning FALSE RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_dpm_get_dhcpv6_opt: 1 mode is not supported RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_fsm_pkt_handler client-mac: c08b.2a79.04e0 after next_event=106, next_state=1 RP/0/RP0/CPU0:Oct 9 10:39:24.542 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_fsm_pkt_handler:no next event RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Received request [handle 0x8c60dbb8] with server-group : RADIUS-TEST RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Authorization request RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: radius_get_prfrd_srvr_info: Retrive Preferred Server info from attr list RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: radius_get_prfrd_srvr_info: Preferred server handle is set to NULL RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: (handle_nas_req) Couldn't retrive the preferred server info RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Trying to find the first radius server to use. RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Created transaction_id (4600002E) for server group C9000002 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: enable_eap_preferred_server check : app id : 0 [nas] 0 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: enable_eap_preferred_server check RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Error while getting authentication type RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Copying remote address 192.168.66.1 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Remote address 192.168.66.1 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Picking the rad id 253:7 sockfd 0x292F8800 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: rctx 0x29343f08 added successfully RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: interface [Loopback0] valid 1 flags 0x0, state 3 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: found ipv4 10.0.9.1 vrf =0x60000000 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: (radius_secret_per_server) type to decrypt 7 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: Send Access-Request to 192.168.66.1:1812 id 253, len 252 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: authenticator 51 5B 99 F0 EE 5A D4 BA - 63 BE 43 D6 03 8A 86 4F RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: Vendor,Cisco [26] 41 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: Cisco AVpair [1] 35 client-mac-address=c08b.2a79.04e0 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: Acct-Session-Id [44] 10 000007ff RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: NAS-Port-Id [87] 17 0/0/101/105.101 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: Vendor,Cisco [26] 23 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: cisco-nas-port [2] 17 0/0/101/105.101 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: User-Name [1] 26 NNI101-101-105 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: Service-Type [6] 6 Outbound[5] RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: User-Password [2] 18 * RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: NAS-Port-Type [61] 6 VIRTUAL_IPOEOQINQ[44] RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: Event-Timestamp [55] 6 1728463164 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: Vendor,Cisco [26] 18 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: cisco-dhcp-client-id[49] 12 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: Vendor,Cisco [26] 23 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: Cisco AVpair [1] 17 dhcp-client-id= RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: Nas-Identifier [32] 14 pe1.koeb3.dk RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: NAS-IP-Address [4] 6 10.0.9.1 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: RADIUS: NAS-IPv6-Address [95] 18 :: RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Got global deadtime 60 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Using global deadtime = 60 sec RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Started server dead detect timer 0x8E24E6F0 for 100 seconds RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Start timer thread rad_ident 253 remote_port 1812 remote_addr 192.168.66.1, socket 690980864 rctx 0x29343f08 RP/0/RP0/CPU0:Oct 9 10:39:24.549 CEST: radiusd[1186]: Successfully sent packet and started timeout handler for rctx 0x29343f08 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: (radius_secret_per_server) type to decrypt 7 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: Radius packet decryption complete with rc = 0 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Received from id 253 192.168.66.1:1812, Access-Accept, len 306 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: authenticator 8C BB 22 2F 99 68 45 BE - 2C B0 33 0D D5 95 F7 0D RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Message-Authenticator[80] 18 b3 ce 6c d0 c6 2f 49 2d 65 0f 8f 38 d4 60 f1 ca RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Framed-IP-Address [8] 6 101.101.0.105 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Framed-IP-Netmask [9] 6 255.255.0.0 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Vendor,Cisco [26] 59 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Cisco AVpair [1] 53 subscriber:sub-qos-policy-out=police-no-res-out-10M RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Vendor,Cisco [26] 57 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Cisco AVpair [1] 51 subscriber:sub-qos-policy-in=police-no-res-in-10M RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Vendor,Cisco [26] 27 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Cisco AVpair [1] 21 addrv6=101:101::105 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Framed-IPv6-Route [99] 19 ::/0 101:101::1 1 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Framed-IPv6-Route [99] 32 2000:0:0:106::/64 101:101::1 1 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Framed-IPv6-Route [99] 32 2000:0:0:107::/64 101:101::1 1 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: RADIUS: Framed-IPv6-Route [99] 30 2000:0:0:108::/64 101:101::1 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: Freeing server group transaction_id (4600002E) RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: pack_length = 306 radius_len = 306 rc=0 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: Calling app inf callback RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: rad_nas_reply_to_client: Received response from id : 253,packet type 2 RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: (rad_nas_reply_to_client) Successfully decoded the response Success: PASS RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: Error while getting authentication type RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: (rad_nas_reply_to_client) Couldn't store the preferred server info RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: (rad_nas_reply_to_client) Send response back to client RP/0/RP0/CPU0:Oct 9 10:39:24.576 CEST: radiusd[1186]: Freeing context :0x55e729343f08 RP/0/RP0/CPU0:Oct 9 10:39:24.588 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP471: Session create response from DPM: sub_label 0x0x55 (85) RP/0/RP0/CPU0:Oct 9 10:39:24.588 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_enqueue_event: after get fsm client-mac=c08b.2a79.04e0 event=6 state=1fsm =0x5600da4cbeb0 ia_type=0 ia_id RP/0/RP0/CPU0:Oct 9 10:39:24.588 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP465: fsm enqueue_event: event:RX_SUBSCRIBER_VALID_EVT client-uid:0x10011e4 (16781796) ia_id:0x0x81a40001 (2175008769) ifh:0x0x16420 (91168) mac:c08b.2a79.04e0 RP/0/RP0/CPU0:Oct 9 10:39:24.588 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6_enqueue_event_pkt: fsm_event =6 fsm_pkt=0x5600da4c92d0wakeup_scheduler=1 RP/0/RP0/CPU0:Oct 9 10:39:24.588 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP384: fsm: sub valid handler client_entry:0xda4d2d70 (3662490992) fsm:0xda4cbeb0 (3662462640) RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 SERVER INTERNAL: TP572: sublabel = 85 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE ERROR: TP2688: prefix null RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE ERROR: TP3517: client or prefix is NULL RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE ERROR: TP3889: Mac address c08b.2a79.04e0:subdb vrf_name INTERNET, vrf_id 1610612740, Client Vrf_name INTERNET, vrf_id 1610612740 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE ERROR: TP2687: Mac c08b.2a79.04e0: client is already in vrf INTERNET, id: 1610612740 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP126: Success Client Uid: 0x10011e4 (16781796) RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: dhcpv6d_subscriber_valid_handler() success RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP382: client_entry:0xda4d2d70 (3662490992) fsm:0xda4cbeb0 (3662462640) RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP381: alloc_all_add_pfx_from_daps: client_entry:0xda4d2d70 (3662490992) fsm:0xda4cbeb0 (3662462640) RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP379: client:0xda4d2d70 (3662490992) fsm:0xda4cbeb0 (3662462640) iaid:0x0x81a40001 (2175008769) RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP380: req_next_addr_pfx_from_daps: client:0xda4d2d70 (3662490992) fsm:0xda4cbeb0 (3662462640) num_req:0 iaid:0x0x81a40001 (2175008769) rc:Success RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP383: Found 1 addr/pfx allocated for client_entry:0xda4d2d70 (3662490992) fsm:0xda4cbeb0 (3662462640) RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP1072: fsm: fwd advt to client client:0xda4d2d70 (3662490992) RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 SERVER INTERNAL: TP572: sublabel = 85 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6D: DHCPv6 aaa option cnt: 0: RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 SERVER INTERNAL: TP5204: DHCPv6 aaa option cnt: 0 for mac: c08b.2a79.04e0 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5213: Read client: 0xda4d2d70 (3662490992) aaa 0 options of total_len: 0 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_create_advt_reply_msg_pdu: Start, client 16781796 dhcpv6_opt 0xda4cfa72 msg_len 18 pdu_len 76 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP252: IANA added RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP249: num iaid: 1 iaid 0x81a40001 (2175008769) RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_create_advt_reply_msg_pdu: IANA Offset 44 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_create_advt_reply_msg_pdu: Final, dhcpv6_opt 0xda4cfaac msg_len 76 pdu_len 76 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: Orig-len = 40, Advertise_pdu_len = 76 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP334: Sending Packet to Client RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP328: Pre-route pkt on interface: Bundle-Ether101.101 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5327: Pre-route pkt on interface: inner vlan 0x65e0 (26080) outer vlan 0x69e0 (27104) RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP765: PKT IPv6 DHCP: Det pkt cntents RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP762: PKT: src:fe80::6a79:9ff:fe73:e0ad dest:fe80::c28b:2aff:fe79:4e0 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP758: PKT: type: ADVERTISE dhcp->type:2 dhcp->xid:15541272 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:SERVERID(2) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 0003000168790973e0a8 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:CLIENTID(1) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 00030001c08b2a7904e0 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:IA-NA(3) optlen:40 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP748: PKT: IAID 0x0x81a40001 (2175008769) T1:0x3c (60) T2:0x60 (96) RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:IAADDR(5) optlen:24 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP729: PKT: IPV6 addr:101:101::105 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP730: PKT: preferred RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP769: PKT: 120 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP731: PKT: valid RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP733: PKT: len:120 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6 BASE EVENT: TP533: Packet sent to client: DUID: 0x00-03-00-01-c0-8b-2a-79-04-e0 IFH:0x16420 (91168) src:fe80::6a79:9ff:fe73:e0ad dst:fe80::c28b:2aff:fe79:4e0 dport:546 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_fsm_pkt_handler client-mac: c08b.2a79.04e0 after next_event=106, next_state=3 RP/0/RP0/CPU0:Oct 9 10:39:24.589 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_fsm_pkt_handler:no next event RP/0/RP0/CPU0:Oct 9 10:39:24.600 CEST: dhcpv6d[1136]: DHCPV6 SERVER INTERNAL: TP5359: SUBDB: Applying stateful addr for sublabel = 85 RP/0/RP0/CPU0:Oct 9 10:39:28.109 CEST: radiusd[1186]: Filling v4 ip address : 3701fa0a RP/0/RP0/CPU0:Oct 9 10:39:28.109 CEST: radiusd[1186]: Filling v4 ip address : 142a8c0 LC/0/0/CPU0:Oct 9 10:39:28.115 CEST: radiusd[295]: show_radius_servers_stats: server encode length 784 RP/0/RP0/CPU0:Oct 9 10:39:28.119 CEST: radiusd[1186]: server encode length 316 LC/0/0/CPU0:Oct 9 10:39:28.122 CEST: radiusd[295]: Filling v4 ip address : 3701fa0a LC/0/0/CPU0:Oct 9 10:39:28.122 CEST: radiusd[295]: Filling v4 ip address : 142a8c0 LC/0/0/CPU0:Oct 9 10:39:28.122 CEST: radiusd[295]: server encode length 316 RP/0/RP0/CPU0:Oct 9 10:39:28.130 CEST: radiusd[1186]: server encode length 64 LC/0/0/CPU0:Oct 9 10:39:28.132 CEST: radiusd[295]: server encode length 64 RP/0/RP0/CPU0:Oct 9 10:39:29.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP319: MEM Alloc, iox_pak:0xda4ce420 (3662472224) size:297 RP/0/RP0/CPU0:Oct 9 10:39:29.542 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: Allocation request for 82 bytes of memory RP/0/RP0/CPU0:Oct 9 10:39:29.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5288: MEM Alloc, DHCPv6-start:0xda4d2440 (3662488640) size:1280 iox_pak:0xda4ce420 (3662472224) RP/0/RP0/CPU0:Oct 9 10:39:29.542 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP320: Received the packet from CLIENT RP/0/RP0/CPU0:Oct 9 10:39:29.542 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP763: PKT IPv6 DHCP: Detailed packet contents RP/0/RP0/CPU0:Oct 9 10:39:29.542 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP762: PKT: src:fe80::c28b:2aff:fe79:4e0 dest:ff02::1:2 RP/0/RP0/CPU0:Oct 9 10:39:29.542 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP758: PKT: type: REQUEST dhcp->type:3 dhcp->xid:9573770 RP/0/RP0/CPU0:Oct 9 10:39:29.542 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:CLIENTID(1) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:29.542 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 00030001c08b2a7904e0 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:SERVERID(2) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 0003000168790973e0a8 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:ELAPSED-TIME(8) optlen:2 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP738: PKT: elaspsed_time:0 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:IA-NA(3) optlen:40 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP748: PKT: IAID 0x0x81a40001 (2175008769) T1:0x0 (0) T2:0x0 (0) RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:IAADDR(5) optlen:24 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP729: PKT: IPV6 addr:101:101::105 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP730: PKT: preferred RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP769: PKT: 120 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP731: PKT: valid RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP733: PKT: len:120 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: dhcpv6d_validate_and_enqueue_msg: msg_type = 3 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP210: dhcpv6d_fill_message_info_structure called RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP214: dhcpv6 message copied to dhcpv6_message_st RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 1 present at 0 offset RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 2 present at 14 offset RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 8 present at 28 offset RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP193: option type 3 present at 34 offset RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP189: dhcpv6d option ia_na present RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP185: suboption 5 present RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP186: dhcpv6d_opt_ia_na_parse,suboptype 5 at offset 50 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_msg_validate 0 3 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP230: status code = 0, partial stat = 0 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP4514: SUCCESS - IAID validation RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP262: message type 0x3 (3) RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP265: case IANA RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP266: ia_id of IANA matching RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP124: MEM ALLOC clone 0xda4d1460 (3662484576), size 0x129 (297) RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: Allocation request for 82 bytes of memory RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5288: MEM Alloc, DHCPv6-start:0xda4d36a0 (3662493344) size:1280 iox_pak:0xda4d1460 (3662484576) RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_enqueue_event: after get fsm client-mac=c08b.2a79.04e0 event=37 state=3fsm =0x5600da4cbeb0 ia_type=0 ia_id RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP465: fsm enqueue_event: event:RX_REQUEST_MSG_EVT client-uid:0x10011e4 (16781796) ia_id:0x0x81a40001 (2175008769) ifh:0x0x16420 (91168) mac:c08b.2a79.04e0 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6_enqueue_event_pkt: fsm_event =37 fsm_pkt=0x5600da252650wakeup_scheduler=1 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE EVENT: TP531: Enqueued packet to FSM from intf Bundle-Ether101.101 DUID:0x00-03-00-01-c0-8b-2a-79-04-e0 iaid:0x0x81a40001 (2175008769) iox_pak:0x0xda4ce420 (3662472224) clone:0x0xda4d1460 (3662484576) RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP125: MEM ALLOC - Free, pkt_info:0xda4ce420 (3662472224), size:297 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5289: MEM ALLOC - Free, DHCPv6-start:0xda4d2440 (3662488640) iox_pak:0xda4ce420 (3662472224) RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP421: set_prev_msg: fsm:0xda4cbeb0 (3662462640) iaid:0x81a40001 (2175008769) prev_pkt_info:0xda254fc0 (3659878336) curr_pktinfo:0xda4d1460 (3662484576) msgtype:0x3 (3) RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP125: MEM ALLOC - Free, pkt_info:0xda254fc0 (3659878336), size:297 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5289: MEM ALLOC - Free, DHCPv6-start:0xda4d3180 (3662492032) iox_pak:0xda254fc0 (3659878336) RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP391: fsm: srv_rx_clnt_req_msg_handler client:0xda4d2d70 (3662490992) fsm:0xda4cbeb0 (3662462640) RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP497: dhcpv6d_dpm_update_session called uid 16781796 chaddr_str c08b.2a79.04e0 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP340: Enter RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_dpm_populate_session: add prefix info allocated ia_id=-2119958527,ia_type=0 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP341: End RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_fsm_pkt_handler client-mac: c08b.2a79.04e0 after next_event=106, next_state=4 RP/0/RP0/CPU0:Oct 9 10:39:29.543 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_fsm_pkt_handler:no next event RP/0/RP0/CPU0:Oct 9 10:39:29.544 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP473: Session Update resp from DPM: sub_label:0x55 (85) trans_id 4788 result 1 LC/0/0/CPU0:Oct 9 10:39:29.614 CEST: dhcpv6d[384]: DHCPV6 BASE INTERNAL: TP138: Rsi_event= 0 LC/0/0/CPU0:Oct 9 10:39:29.614 CEST: dhcpv6d[384]: DHCPV6 BASE INTERNAL: TP136: IfH= 0x16860 (92256) vrf_id= 0x60000004 (1610612740) vrfName= INTERNET RP/0/RP1/CPU0:Oct 9 10:39:29.615 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP138: Rsi_event= 0 RP/0/RP1/CPU0:Oct 9 10:39:29.615 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP136: IfH= 0x16860 (92256) vrf_id= 0x60000004 (1610612740) vrfName= INTERNET RP/0/RP0/CPU0:Oct 9 10:39:29.615 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP138: Rsi_event= 0 RP/0/RP0/CPU0:Oct 9 10:39:29.615 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP136: IfH= 0x16860 (92256) vrf_id= 0x60000004 (1610612740) vrfName= INTERNET RP/0/RP0/CPU0:Oct 9 10:39:29.663 CEST: dhcpv6d[1136]: DHCPV6 SERVER INTERNAL: TP767: vrfname:INTERNET sublabel:85 RP/0/RP0/CPU0:Oct 9 10:39:29.663 CEST: dhcpv6d[1136]: DHCPV6 BASE ERROR: TP3703: dhcpv6d_update_client_subdb_attr: client is already in vrf: 1610612740 RP/0/RP0/CPU0:Oct 9 10:39:29.965 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP476: Session Up Ntf rcvd sub_label 0x0x55 (85) trans_id 4788 RP/0/RP0/CPU0:Oct 9 10:39:29.965 CEST: dhcpv6d[1136]: DHCPV6D: DPM session UP for sub-label 0x55 RP/0/RP0/CPU0:Oct 9 10:39:29.965 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_enqueue_event: after get fsm client-mac=c08b.2a79.04e0 event=9 state=4fsm =0x5600da4cbeb0 ia_type=0 ia_id RP/0/RP0/CPU0:Oct 9 10:39:29.965 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP465: fsm enqueue_event: event:RX_SUBSCRIBER_UP_EVT client-uid:0x10011e4 (16781796) ia_id:0x0x81a40001 (2175008769) ifh:0x0x16420 (91168) mac:c08b.2a79.04e0 RP/0/RP0/CPU0:Oct 9 10:39:29.965 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6_enqueue_event_pkt: fsm_event =9 fsm_pkt=0x5600da4cbdb0wakeup_scheduler=1 RP/0/RP0/CPU0:Oct 9 10:39:29.965 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP108: Success, Client uid 0x10011e4 (16781796) RP/0/RP0/CPU0:Oct 9 10:39:29.965 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP119: Success, client uid: 0x10011e4 (16781796) RP/0/RP0/CPU0:Oct 9 10:39:29.965 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP434: fsm Session UP received: client_entry:0xda4d2d70 (3662490992) fsm:0xda4cbeb0 (3662462640) RP/0/RP0/CPU0:Oct 9 10:39:29.965 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP501: dhcpv6d_dpm_get_subscr_ifh: dpm_get_subscr_ifh succes sub_label 0x0x55 (85) uid 16781796 subscr_ifh 0x0x16860 (92256) RP/0/RP0/CPU0:Oct 9 10:39:29.966 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP433: fsm Route add all client:0xda4d2d70 (3662490992) RP/0/RP0/CPU0:Oct 9 10:39:29.966 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP680: ADD Route add to buffer for uid: 0x10011e4 (16781796) iaid: 0x81a40001 (2175008769) index: 1 RP/0/RP0/CPU0:Oct 9 10:39:29.966 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_get_nexthop: ENTRY RP/0/RP0/CPU0:Oct 9 10:39:29.966 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_get_nexthop: ENTRY 123 RP/0/RP0/CPU0:Oct 9 10:39:29.966 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP694: add route: buff_p:0x4ce4a030 (1290051632) prefix:101:101::105/128 client-uid: 0x10011e4 (16781796) vrf: INTERNET RP/0/RP0/CPU0:Oct 9 10:39:29.966 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP695: add route: nexthop::: sifh:0x0x16860 (92256) pifh:0x0x16420 (91168) is_sub:1 RP/0/RP0/CPU0:Oct 9 10:39:29.966 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_fsm_pkt_handler client-mac: c08b.2a79.04e0 after next_event=106, next_state=5 RP/0/RP0/CPU0:Oct 9 10:39:29.966 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_fsm_pkt_handler:no next event RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6_enqueue_event_pkt: fsm_event =59 fsm_pkt=0x5600da4cbdb0wakeup_scheduler=1 RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP721: Dhcpv6 Rib timer exp event RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP683: Dhcpv6 Rib batch send RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP459: Route add succes: prefix 101:101::105/128 , nexthop :: , vrf INTERNET RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP456: Found client entry : for uid: 0x10011e4 (16781796) , ia_id: 0x81a40001 (2175008769) RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_enqueue_event: after get fsm client-mac=c08b.2a79.04e0 event=22 state=5fsm =0x5600da4cbeb0 ia_type=0 ia_id RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP465: fsm enqueue_event: event:SUB_ROUTE_ADD_SUCC_EVT client-uid:0x10011e4 (16781796) ia_id:0x0x81a40001 (2175008769) ifh:0x0x16860 (92256) mac:c08b.2a79.04e0 RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6_enqueue_event_pkt: fsm_event =22 fsm_pkt=0x5600da4ced90wakeup_scheduler=1 RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP458: Enqueue rib event Success: for uid: 0x10011e4 (16781796) , ia_id: 0x81a40001 (2175008769) RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP3901: Base event handler rc=Success RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP442: fsm route_add_succ handler: client_entry:0xda4d2d70 (3662490992) fsm:0xda4cbeb0 (3662462640) RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_fsm_pkt_handler client-mac: c08b.2a79.04e0 after next_event=26, next_state=21 RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP420: fsm: fsm queieng event:SUB_BOUND_EVT state:DHCPV6_NO_STATE_CHANGE ia_id:0x0x81a40001 (2175008769) pkt_info:0x0x0 (0) RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6_enqueue_event_pkt: fsm_event =26 fsm_pkt=0x5600da4ced90wakeup_scheduler=1 RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP385: fsm: get_addr_info:0xd9fcc430 (3657221168) client:0xda4d2d70 (3662490992) iaid:0x81a40001 (2175008769) num_addr:1 RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP414: fsm stopped timer:2 fsm:0xda4cbeb0 (3662462640) ia_id:0x81a40001 (2175008769) timer_p:0xda4cc100 (3662463232) RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP385: fsm: get_addr_info:0xd9fcc430 (3657221168) client:0xda4d2d70 (3662490992) iaid:0x81a40001 (2175008769) num_addr:1 RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP463: get_lease_time_val: fsm:0xda4cbeb0 (3662462640) ia_id:0x81a40001 (2175008769) timer_val:120 RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP413: SUB_LEASE_TIMER secs_to_expiry:120 fsm:0x0xda4cbeb0 (3662462640) ia_id:0x0x81a40001 (2175008769) RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP429: fsm moving to bound state client:0xda4d2d70 (3662490992) RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP444: reply msg to client chaddr:c08b.2a79.04e0 client:0xda4d2d70 (3662490992) iaid:0x81a40001 (2175008769) clflag:0x0x8880811 (143132689) fsmflag:0xf1 (241) RP/0/RP0/CPU0:Oct 9 10:39:30.066 CEST: dhcpv6d[1136]: DHCPV6 SERVER INTERNAL: TP572: sublabel = 85 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6D: DHCPv6 aaa option cnt: 0: RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 SERVER INTERNAL: TP5204: DHCPv6 aaa option cnt: 0 for mac: c08b.2a79.04e0 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5213: Read client: 0xda4d2d70 (3662490992) aaa 0 options of total_len: 0 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_create_advt_reply_msg_pdu: Start, client 16781796 dhcpv6_opt 0xd9fbffb2 msg_len 18 pdu_len 76 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP252: IANA added RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP249: num iaid: 1 iaid 0x81a40001 (2175008769) RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_create_advt_reply_msg_pdu: IANA Offset 44 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_create_advt_reply_msg_pdu: Final, dhcpv6_opt 0xd9fbffec msg_len 76 pdu_len 76 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP334: Sending Packet to Client RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP328: Pre-route pkt on interface: Bundle-Ether101.101 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5327: Pre-route pkt on interface: inner vlan 0x65e0 (26080) outer vlan 0x69e0 (27104) RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP765: PKT IPv6 DHCP: Det pkt cntents RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP762: PKT: src:fe80::6a79:9ff:fe73:e0ad dest:fe80::c28b:2aff:fe79:4e0 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP758: PKT: type: REPLY dhcp->type:7 dhcp->xid:9573770 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:SERVERID(2) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 0003000168790973e0a8 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:CLIENTID(1) optlen:10 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP725: PKT: duidbuf: 00030001c08b2a7904e0 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:IA-NA(3) optlen:40 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP748: PKT: IAID 0x0x81a40001 (2175008769) T1:0x3c (60) T2:0x60 (96) RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP723: PKT: optype:IAADDR(5) optlen:24 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP729: PKT: IPV6 addr:101:101::105 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP730: PKT: preferred RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP769: PKT: 120 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP731: PKT: valid RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 PACKET: TP733: PKT: len:120 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE EVENT: TP533: Packet sent to client: DUID: 0x00-03-00-01-c0-8b-2a-79-04-e0 IFH:0x16420 (91168) src:fe80::6a79:9ff:fe73:e0ad dst:fe80::c28b:2aff:fe79:4e0 dport:546 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP108: Success, Client uid 0x10011e4 (16781796) RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP119: Success, client uid: 0x10011e4 (16781796) RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_fsm_pkt_handler client-mac: c08b.2a79.04e0 after next_event=106, next_state=6 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6D: dhcpv6d_fsm_pkt_handler:no next event RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP108: Success, Client uid 0x10011e4 (16781796) RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP119: Success, client uid: 0x10011e4 (16781796) RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP125: MEM ALLOC - Free, pkt_info:0xda4d1460 (3662484576), size:297 RP/0/RP0/CPU0:Oct 9 10:39:30.067 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP5289: MEM ALLOC - Free, DHCPv6-start:0xda4d36a0 (3662493344) iox_pak:0xda4d1460 (3662484576) RP/0/RP1/CPU0:Oct 9 10:39:30.647 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP357: client chkpt NDT add RP/0/RP1/CPU0:Oct 9 10:39:30.647 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP778: Client chkpt NDT save RP/0/RP1/CPU0:Oct 9 10:39:30.647 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP103: Recovering client client->uid 0x10011e4 (16781796) RP/0/RP1/CPU0:Oct 9 10:39:30.647 CEST: dhcpv6d[1136]: DHCPV6 BASE ERROR: TP3746: create_client_from_chkpt failed RP/0/RP1/CPU0:Oct 9 10:39:30.647 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP778: Client chkpt NDT save RP/0/RP1/CPU0:Oct 9 10:39:30.647 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP103: Recovering client client->uid 0x10011e4 (16781796) RP/0/RP1/CPU0:Oct 9 10:39:30.647 CEST: dhcpv6d[1136]: DHCPV6 BASE ERROR: TP3746: create_client_from_chkpt failed RP/0/RP1/CPU0:Oct 9 10:39:30.647 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP778: Client chkpt NDT save RP/0/RP1/CPU0:Oct 9 10:39:30.647 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP103: Recovering client client->uid 0x10011e4 (16781796) RP/0/RP1/CPU0:Oct 9 10:39:30.647 CEST: dhcpv6d[1136]: DHCPV6D: DHCPV6_INFO: Adding client to Bundle-Ether101.101 interface tree RP/0/RP1/CPU0:Oct 9 10:39:30.647 CEST: dhcpv6d[1136]: DHCPV6 BASE INTERNAL: TP104: Success Client uid: 0x10011e4 (16781796)