Feb 16 08:51:30.169195 [MSTR][DEBUG] jdhcpd_mirror_timer_expire: Nothing to purge: clients restoration complete Feb 16 08:51:30.169213 [MSTR][DEBUG] jdhcpd_mirror_timer_clear: Destroying HA purge timer Feb 16 08:51:34.381047 [MSTR][INFO] jdhcpd_smd_process_v4_pkt: **** 420 byte packet received on IFL index 536870916 **** Feb 16 08:51:34.381079 [MSTR][DEBUG] jdhcpd_packet_new: PACKET - Allocated new v4 packet 0xa097390 Feb 16 08:51:34.381092 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] jdhcpd_io_process_ip_packet: LOCAL: recv pkt; sa 0.0.0.0; da 255.255.255.255; src_port 68; dst_port 67; len 300 Feb 16 08:51:34.381105 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP from == 0.0.0.0, port == 68 ]-- Feb 16 08:51:34.381111 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP size == 300, op == 1 ]-- Feb 16 08:51:34.381118 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP flags == 0 ]-- Feb 16 08:51:34.381124 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP htype == 1, hlen == 6 ]-- Feb 16 08:51:34.381130 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP hops == 0, xid == ad633900 ]-- Feb 16 08:51:34.381137 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP secs == 5, flags == 0000 ]-- Feb 16 08:51:34.381144 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP ciaddr == 0.0.0.0 ]-- Feb 16 08:51:34.381150 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP yiaddr == 0.0.0.0 ]-- Feb 16 08:51:34.381156 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP siaddr == 0.0.0.0 ]-- Feb 16 08:51:34.381173 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP giaddr == 0.0.0.0 ]-- Feb 16 08:51:34.381184 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP chaddr == 64 70 02 b4 eb 63 00 00 00 00 00 00 00 00 00 00 ]-- Feb 16 08:51:34.381191 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP sname == ]-- Feb 16 08:51:34.381197 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ DHCP/BOOTP file == ]-- Feb 16 08:51:34.381214 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ OPTION code 53, len 1, data DHCP-DISCOVER ]-- Feb 16 08:51:34.381223 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ OPTION code 12, len 8, data 43 6c 69 65 6e 74 2d 31 ]-- Feb 16 08:51:34.381235 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] --[ OPTION code 55, len 18, data 01 1c 02 79 0f 06 0c 28 29 2a 1a 77 03 79 f9 21 fc 2a ]-- Feb 16 08:51:34.381241 [MSTR][INFO] [default:default][SVR][INET][ge-0/0/0.3221225472] --[ OPTION code 255, len 0 ]-- Feb 16 08:51:34.381253 [MSTR][DEBUG] client_key_compose: Composing key (0xa7a3dc0) for cid_l 0, cid NULL, mac 64 70 02 b4 eb 63, htype 1, subnet 19.205.49.254, ifindx 0, opt82_l 0, opt82 NULL Feb 16 08:51:34.381261 [MSTR][DEBUG] client_key_compose: Successfully composed CK_TYPE_HW_ADDR_ON_SUBNET (2) client key object. Feb 16 08:51:34.381270 [MSTR][DEBUG] client_key_print: key_type CK_TYPE_HW_ADDR_ON_SUBNET (2): subnet 19.205.49.254, MAC htype 1, Addr 64 70 02 b4 eb 63 Feb 16 08:51:34.381276 [MSTR][DEBUG] client_key_print: key_type CK_TYPE_HW_ADDR_ON_SUBNET (2) other fields: subnet 19.205.49.254, ifindex 0, opt82_len 0, - Feb 16 08:51:34.381284 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] jdhcpd_find_client_from_client_pdu: BOOTPREQUEST could not find client table ent Feb 16 08:51:34.381297 [MSTR][NOTE] jdhcpd_packet_handle: RECEIVE DISCOVER: stats_safd 0x0 , safd 0xa77cd80 ge-0/0/0.3221225472 Feb 16 08:51:34.381305 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] jdhcpd_process_forward_only_or_drop: Returning ... forward-only flags not set (flags=deaddead, rc_flags 8a4080d) for routing context 0 Feb 16 08:51:34.381316 [MSTR][INFO] cedb_entry_new_common: New client (0xa0fa400), family=INET, index=65546 Feb 16 08:51:34.381322 [MSTR][DEBUG] cedb_entry_new_common: DHCPv4 client entry ADDED, app_type 1 routing context default:default Feb 16 08:51:34.381334 [MSTR][DEBUG] jdhcpd_name_2_const: ifl ge-0/0/0.3221225472 fpc 0 pic 0 port 0 chnl 4294967295 chnl_max 4294967295 unit 3221225472 Feb 16 08:51:34.381342 [MSTR][INFO] [default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] cedb_entry_new_common: Newly created client entry is flow-based. Feb 16 08:51:34.381350 [MSTR][DEBUG][default:default][SVR][INET][SID=0] _jdhcpd_stale_timer_add: Added stale-timer for client:0xa0fa400 Feb 16 08:51:34.381356 [MSTR][DEBUG][default:default][SVR][INET][SID=0] jdhcpd_packet_handle: new client table entry created for ifindex 536870916 Feb 16 08:51:34.381363 [MSTR][DEBUG] jdhcpd_name_2_const: ifl ge-0/0/0.3221225472 fpc 0 pic 0 port 0 chnl 4294967295 chnl_max 4294967295 unit 3221225472 Feb 16 08:51:34.381370 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472] jdhcpd_process_offer_advertise_delay: Returning ... offer delay not set (flags=0, rc_flags 8a4080d) for routing context 0, rc(a0b4e00) Feb 16 08:51:34.381379 [MSTR][DEBUG][default:default][SVR][INET][SID=0] jdhcpd_packet_handle: Set client next-hop mac addr: 64 70 02 b4 eb 63 Feb 16 08:51:34.381386 [MSTR][INFO] [default:default][SVR][INET][SID=0] JDHCPD_CLIENT_EVENT: Client(0xa0fa400) got event CLIENT_EVENT_DISCOVER_PDU in state LOCAL_SERVER_STATE_INIT flags 1: 0x0 2: 0x80000 3: 0x0 nm: 0x0 Feb 16 08:51:34.381405 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_profile_get: No snapshot handle to recover SDB profile name Feb 16 08:51:34.381412 [MSTR][INFO] [default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_profile_get: Profile_get using regular profile dhcp-local-server-profile Feb 16 08:51:34.381421 [MSTR][INFO] jdhcpd_client_set_pkt: *********** Setting v4 packet 0xa097390 Feb 16 08:51:34.381437 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_save_incoming_packet_options: Updated client incoming dhcp-options with len 33 dhcp-options 35 01 01 0c 08 43 6c 69 65 6e 74 2d 31 37 12 01 1c 02 79 0f 06 0c 28 29 2a 1a 77 03 79 f9 21 fc 2a Feb 16 08:51:34.382511 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_decline_new_client_session: Incremented global in-flight logins - count is 1, session id 0 Feb 16 08:51:34.383936 [MSTR][DEBUG] jdhcpd_service_get: Service_get NO service name Feb 16 08:51:34.383947 [MSTR][INFO] [default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_access_profile_get: access_profile_get using access-profile name external-radius Feb 16 08:51:34.383957 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_sdb_create_session: phy device name ge-0/0/0 Feb 16 08:51:34.383966 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_sdb_create_session: setting LR name default Feb 16 08:51:34.383983 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_sdb_create_session: setting RI name default Feb 16 08:51:34.383992 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_sdb_create_session: sdb username 6470.02b4.eb63 Feb 16 08:51:34.383999 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_sdb_create_session: setting profile name dhcp-local-server-profile Feb 16 08:51:34.384005 [MSTR][INFO] [default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_sdb_create_session: setting access profile name external-radius Feb 16 08:51:34.384013 [MSTR][INFO] [default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_sdb_create_session: setting inner vlan_ID 384 Feb 16 08:51:34.384019 [MSTR][INFO] [default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_sdb_create_session: setting underlying interface ge-0/0/0.3221225472 Feb 16 08:51:34.384028 [MSTR][INFO] [default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_sdb_create_session: setting MAC addr 64:70:02:b4:eb:63 Feb 16 08:51:34.384037 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_session_db_create_v4_session_attributes: Setting IPv4 Addr Key 19.205.49.254 Feb 16 08:51:34.384052 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_session_db_create_v4_session_attributes: dhcp_options len 33, data 35 01 01 0c 08 43 6c 69 65 6e 74 2d 31 37 12 01 1c 02 79 0f 06 0c 28 29 2a 1a 77 03 79 f9 21 fc 2a Feb 16 08:51:34.384070 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_session_db_create_v4_session_attributes: adding dhcp_header to SDB len 44, data 01 01 06 00 ad 63 39 00 00 05 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 64 70 02 b4 eb 63 00 00 00 00 00 00 00 00 00 00 Feb 16 08:51:34.384077 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_sdb_create_session: setting physical interface name ge-0/0/0 Feb 16 08:51:34.384083 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=0] jdhcpd_sdb_create_session: setting authd phy ifd name ge-0/0/0 Feb 16 08:51:34.385039 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_sdb_create_session: session DB create got INET entry id 12 Feb 16 08:51:34.385047 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_sdb_create_session: Adding shmlog fileter success for session id 12 Feb 16 08:51:34.385063 [MSTR][INFO] [default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_persistent_is_entry_in_file_allowed: client_type=1, client->state=0 Feb 16 08:51:34.385071 [MSTR][INFO] [default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_fill_client_sdb_private_data: Mirroring disabled (ha_graceful_switchover false) :: Feb 16 08:51:34.385079 [MSTR][INFO] [default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_fill_client_sdb_private_data: Allocated memory to store in SDB, total mirrored length 260, client length 260, dual stack peer client length 0, session-id 12 Feb 16 08:51:34.385088 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_write_private_data: Saved NON-DEMUX interface name ge-0/0/0.3221225472 into mirroring ha_info Feb 16 08:51:34.385096 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_write_private_data: ++++Added TLV for WHOLESALE LR name default length 7 Feb 16 08:51:34.385102 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_write_private_data: ++++Added TLV for WHOLESALE RI name default length 7 Feb 16 08:51:34.385108 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_write_private_data: Added TLV for last_packet_time length 4, last_packet_time: 1550307094 Feb 16 08:51:34.385115 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_write_private_data: ++++Added TLV for access profile name length 15, profile_name: external-radius Feb 16 08:51:34.385122 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_write_private_data: ++++Added TLV for Incoming dhcp options length 33 Feb 16 08:51:34.385133 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_mirrorsdb_add_client: Successfully saved private data for 0.0.0.0 in SDB, length 260 Feb 16 08:51:34.385140 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_client_correlation_id_update: setting the client correlation_id 1 in state 0. Feb 16 08:51:34.385147 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_set_fsm_state: FSM state change : LOCAL_SERVER_STATE_INIT --> LOCAL_SERVER_STATE_WAIT_AUTH_REQ Feb 16 08:51:34.385155 [MSTR][DEBUG][default:default][SVR][INET][ge-0/0/0.3221225472][SID=12] jdhcpd_auth_request: auth-request attempt initiated: flags1:0x20020; flags2:0x84000l flags3:0 Feb 16 08:51:34.385160 Before adding to teardown list, jdhcpd_inst.authd_restart_teardown_count 0 Feb 16 08:51:34.385165 LIST IS EMPTY, jdhcpd_inst.authd_restart_teardown_count 0 Feb 16 08:51:34.385169 Added client w/ session_id 12 in state LOCAL_SERVER_STATE_WAIT_AUTH_REQ, to authd_restart_teardown list, count authd_restart_teardown_count 1