R510/R600 WAN DHCP IP issue

[klui]

I have an R510 with Unleashed 200.6 and R600 with Unleashed 200.7 connected to a Juniper SRX 240 firewall. The SRX has a DHCP server with reservation assigned for the APs' WAN but for some reason the IP address is being acquired and released multiple times. If I don't have reservation assigned the IP addresses will go from my subnet's low range and increasing around every 15-30 seconds.

I know I can always assign a static IP to the WAN address, but would like to correct this problem. I downloaded the logs and here's a snippet:

Aug  2 07:59:41 Ruckus-Unleashed-R510-b0 syslog:  AP get the broadcast ip in its subnet, the broadcast ip is x.y.z.255
 .
 .
Aug  2 07:59:43 Ruckus-Unleashed-R510-b0 syslog: pubnubd_debug: pid=2070, debug_init[82]: Set log_level file to [/tmp/pubnubd_loglevel].
Aug  2 07:59:43 Ruckus-Unleashed-R510-b0 syslog: pubnubd: set debug_log_level to 3, internal log_level to 1.
Aug  2 07:59:43 Ruckus-Unleashed-R510-b0 syslog: pubnubd_debug: pid=2070, main[729]: missing publish-key|subscribe-key|channel-name, pending service.
Aug  2 07:59:43 Ruckus-Unleashed-R510-b0 syslog: pubnubd_debug: pid=2070, handle_state_change[644]: state change [(null)]->[PENDING].
Aug  2 07:59:43 Ruckus-Unleashed-R510-b0 syslog: pubnubd_debug: pid=2070, handle_state_change[664]: 'Fri Aug  2 07:59:43 2019': service recovered
Aug  2 07:59:43 Ruckus-Unleashed-R510-b0 syslog: pid=1160, monitor():FileCache synced
Aug  2 07:59:44 Ruckus-Unleashed-R510-b0 rsmd[86]: tacmon .......... [stopped] (0.020)
Aug  2 07:59:46 Ruckus-Unleashed-R510-b0 syslogd (inetutils 1.4.0): restart (remote reception)
Aug  2 07:59:46 Ruckus-Unleashed-R510-b0 sys_wrapper: Start Bind DHCP server
Aug  2 07:59:46 Ruckus-Unleashed-R510-b0 syslog: sqlited, adding AP group: 1, wlangrp_id[0]=1, wlangrp_id[1]=1
 .
 .
Aug  2 07:59:57 Ruckus-Unleashed-R510-b0 syslog: (ap state) AP begin to join ac.
Aug  2 07:59:57 Ruckus-Unleashed-R510-b0 syslog: Cannot notify kernel for Upd AP Param x.y.z.252(0) event, -- No such file or directory(2)
Aug  2 07:59:57 Ruckus-Unleashed-R510-b0 ZD-APMgr: Cannot notify kernel for Upd AP Param x.y.z.252(12225) event, -- No such file or directory(2)
Aug  2 07:59:57 Ruckus-Unleashed-R510-b0 syslog: WTP join cfm response, lwapp_notify_kernel_ip_addr [nat  ipv4].   nat_ipv4_addr: x.y.z.252    port: 0    family: 2
 .
 .
Aug  2 07:59:57 Ruckus-Unleashed-R510-b0 syslog: System time was synced
Aug  2 07:59:57 Ruckus-Unleashed-R510-b0 syslog: lwapp_proc_vendor_me, 4685 Failed to set set lbs port(parameter value out of range)
Aug  2 07:59:57 Ruckus-Unleashed-R510-b0 syslog: eventd_to_syslog():AP[M:A:C:A:D:D] joins with uptime [132] s and last disconnected reason [AP Restart : power cycle]
Aug  2 07:59:57 Ruckus-Unleashed-R510-b0 syslog: lwapp_proc_vendor_me: setting ip mode, before=1, now=1
Aug  2 07:59:57 Ruckus-Unleashed-R510-b0 syslog: [lwapp_update_wtp_vendor:7434] : company=4bss, strlen(company)=4

x.y.z.252 is the reserved IP. The problem is if I were to disable reservation I'd never login and obtain the logs before the WAN IP changes again.

I'd like to add that with reservation things work. All my VLANs (the WAN port is connected to a trunk and it's responding to the correct pool assigned to the port's PVID) and SSIDs all function. I've only tested with 1 client but it has internet access. I can also ping the APs by their reserved IPs even though after several minutes the address binding entry will disappear from the SRX. The DHCP server is fine--it works as expected with a Juniper AP and Windows notebook.

I have been troubleshooting on the R600 but since I have a brand new R510, I decided to see if it was an issue with the R600. The R510 was just configured using the setup wizard.


Thanks!

[michael_brado]

My initial thought is that both APs should use the same/latest Unleashed, and are there still problems?

[klui]

Hi,

The R600 is running the latest 200.7.10.102.64.

On the R510, it was running 200.6.10.1.308. I upgraded to each FW available:

• 200.6.10.1.312
• 200.7.10.2.339
• 200.7.10.102.64

And I get the same behavior. After the FW is applied but before the login webUI is shown my DHCP server already received 2 releases. But unfortunately there are no entries about releases from the AP logs. About the APs' configurations: I use DHCP on the WAN IP. The R510 only has one SSID under vlan 1. On the R600 I have 2 SSIDs but they're non-1 VLANs. I don't hide the SSIDs nor do I enable Enable Dynamic VLAN.

On the SRX, I do see the releases after I've enable DHCP logging. I'm not too familiar with the SRX's DHCP logs, so I am showing the entries created between DHCP sessions 11 and 12. Not sure how useful the following long snippet is for the R600, IP reservation x.y.z.251.

Thanks

Aug  2 04:39:55 cid_l 7 cidp 1 mac 0x74 3e 2b M A C htype 1 subnet c0a803fe ifindx 0 key_type 1
Aug  2 04:39:55 DHCPv4 client entry ADDED, app_type 1 routing context default:default
Aug  2 04:39:55 Service_get NO service name
Aug  2 04:39:55 jdhcpd_sdb_create_session: phy device name vlan
Aug  2 04:39:55 jdhcpd_sdb_create_session: INET, setting LR name  default
Aug  2 04:39:55 jdhcpd_sdb_create_session: INET, setting RI name  default
Aug  2 04:39:55 jdhcpd_sdb_create_session: INET, setting underlying interface vlan.0
Aug  2 04:39:55 jdhcpd_sdb_create_session: INET, setting logical if name  vlan.0
Aug  2 04:39:55 jdhcpd_sdb_create_session: INET, setting MAC addr 74:3e:2b:M:A:C
Aug  2 04:39:55 jdhcpd_session_db_create_v4_session_attributes: INET, setting IP addr Key x.y.z.254
Aug  2 04:39:55 jdhcpd_session_db_create_v4_session_attributes: INET, dhcp_options len  40, data 35 01 01 3d 07 01 74 3e 2b M A C 3c 0a 52 75 63 6b 75 73 20 43 50 45 39 02 02 40 37 0a 01 02 03 06 0c 0f 1c 2a 2b 2c
Aug  2 04:39:55 jdhcpd_sdb_create_session: session DB create got INET entry id 11
Aug  2 04:39:55 Mirroring disabled (ha_graceful_switchover false) 0.0.0.0
Aug  2 04:39:55 Allocated memory to store in SDB, sizeof(jdhcpd_ha_client_header_t) 88 + total_tlvs_len 85 (sizeof(client->session_id) is 11)
Aug  2 04:39:55 Saved NON-DEMUX interface name vlan.0 into mirroring ha_info
Aug  2 04:39:55 Added TLV for DHCPv4 CLIENT ID from client key, length 7
Aug  2 04:39:55 Added TLV for DHCPv6 LinkLocal Address, length 16
Aug  2 04:39:55 Successfully saved private data for 0.0.0.0 in SDB, length 173
Aug  2 04:39:55 SDB ACK will notify us for LOCAL-SERVER client session Id 11
Aug  2 04:39:55 Successful SDB Mirrored Ack: returned code SDB_SUCCESS for session id 11
Aug  2 04:39:55 SDB mirroring ACK received
Aug  2 04:39:55 jdhcpd_auth_request: auth-request attempt initiated for session 11: flags1:0x20000; flags2:0
Aug  2 04:39:55 Before adding to teardown list, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:39:55 LIST IS EMPTY, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:39:55 Added client w/ session_id 11 in state LOCAL_SERVER_STATE_WAIT_AUTH_REQ, to authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:39:55 using Service-Profile-Name 
Aug  2 04:39:55 jdhcpd_authenticate_rply_proc: Got reply for auth_request, reply auth_rqst_id = 65549- (session Id 11)
Aug  2 04:39:55 Request to remove client w. session_id 11 in state 1 from authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:39:55 Removed LAST client from authd_restart teardown list, zero out COUNT authd_restart_teardown_count
Aug  2 04:39:55 jdhcpd_authenticate_rply_proc: auth login reply status for session 11: result:1; flags1:0x20000; flags2:0x10
Aug  2 04:39:55 got IP address jdhcpd_session_db_client_info_get x.y.z.251
Aug  2 04:39:55 got net mask jdhcpd_session_db_client_info_get 255.255.255.0
Aug  2 04:39:55 got ls name jdhcpd_session_db_client_info_get default
Aug  2 04:39:55 got ri name jdhcpd_session_db_client_info_get default
Aug  2 04:39:55 got pool name DefaultPool, len 11
Aug  2 04:39:55 Auth request reply ip x.y.z.251, pool name DefaultPool
Aug  2 04:39:55 jdhcpd_pool_find: cfg 7d8000, pool_name 8caf10, DefaultPool
Aug  2 04:39:55 jdhcpd_pool_find: Pool DefaultPool  found 0x7e0800 w/ cfg container 0x7d8000
Aug  2 04:39:55 jdhcpd_pool_find: cfg 7d8000, pool_name 8caf10, DefaultPool
Aug  2 04:39:55 jdhcpd_pool_find: Pool DefaultPool  found 0x7e0800 w/ cfg container 0x7d8000
Aug  2 04:39:55 Got AUTH reply- no profile- LOCAL_SERVER_STATE_CLIENT_SELECTING, updating SDB with client session state SDB_SESSION_STATE_CONFIGURED
Aug  2 04:39:55 jdhcpd_session_db_client_session_state_set: setting session state 1
Aug  2 04:39:55 jdhcpd_session_db_client_session_state_set: setting action bits to 3, config-bits:0x2 0 0 0 0 
Aug  2 04:39:55 libstats clear iflstats passed session_id 11

Aug  2 04:39:58 cid_l 7 cidp 1 mac 0x74 3e 2b M A C htype 1 subnet c0a803fe ifindx 0 key_type 1
Aug  2 04:39:58 jdhcpd_pool_find: cfg 7d8000, pool_name 8caf10, DefaultPool
Aug  2 04:39:58 jdhcpd_pool_find: Pool DefaultPool  found 0x7e0800 w/ cfg container 0x7d8000
Aug  2 04:39:58 jdhcpd_pool_find: cfg 7d8000, pool_name 8caf10, DefaultPool
Aug  2 04:39:58 jdhcpd_pool_find: Pool DefaultPool  found 0x7e0800 w/ cfg container 0x7d8000
Aug  2 04:39:58 cid_l 7 cidp 1 mac 0x74 3e 2b M A C htype 1 subnet c0a803fe ifindx 0 key_type 1
Aug  2 04:39:58 jdhcpd_pool_find: cfg 7d8000, pool_name 8caf10, DefaultPool
Aug  2 04:39:58 jdhcpd_pool_find: Pool DefaultPool  found 0x7e0800 w/ cfg container 0x7d8000
Aug  2 04:39:58 rc_entry_sus_find_ipaddr: TYPE 1 for IP ADDR x.y.z.254 in routing context LR default/RI default
Aug  2 04:39:58 Entry w/ ref_count 1 found when searching 'subunit by ip-addr' for IP ADDR x.y.z.254 in routing context LR default/RI default
Aug  2 04:39:58 jdhcpd_auth_profile_activate: auth activate req for session 11: flags1:0x20000; flags2:0x10
Aug  2 04:39:58 Before adding to teardown list, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:39:58 LIST IS EMPTY, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:39:58 Added client w/ session_id 11 in state LOCAL_SERVER_STATE_CLIENT_SELECTING, to authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:39:58 jdhcpd_service_activated_rply_proc: Got service-activated reply rqst_id = 65549 (session Id 11)
Aug  2 04:39:58 Request to remove client w. session_id 11 in state 16 from authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:39:58 Removed LAST client from authd_restart teardown list, zero out COUNT authd_restart_teardown_count
Aug  2 04:39:58 Mirroring disabled (ha_graceful_switchover false) x.y.z.251
Aug  2 04:39:58 Allocated memory to store in SDB, sizeof(jdhcpd_ha_client_header_t) 88 + total_tlvs_len 85 (sizeof(client->session_id) is 11)
Aug  2 04:39:58 Saved NON-DEMUX interface name vlan.0 into mirroring ha_info
Aug  2 04:39:58 Added TLV for DHCPv4 CLIENT ID from client key, length 7
Aug  2 04:39:58 Added TLV for DHCPv6 LinkLocal Address, length 16
Aug  2 04:39:58 Successfully saved private data for x.y.z.251 in SDB, length 173
Aug  2 04:39:58 SDB ACK will notify us for mirroring ADD for x.y.z.251, client session Id 11
Aug  2 04:39:58 Successful SDB Mirrored Ack: returned code SDB_SUCCESS for session id 11
Aug  2 04:39:58 Atemmpt to jdhcpd_rtsock_do_arp for non-PASSIVE client x.y.z.251, ifindex 69, l2 ifindex 0
Aug  2 04:39:58 jdhcpd_rtsock_do_arp: L3ifindex (ifs) = 69, L2ifindex 0, addr_fam INET, operation 1
Aug  2 04:39:58 Add media route for x.y.z.251 on interface vlan.0 (hw 74:3e:2b:M:A:C) perm
Aug  2 04:39:58 SUCCESSFULL Add media route for x.y.z.251 on interface vlan.0 (hw 74:3e:2b:M:A:C) perm, l2Int 
Aug  2 04:39:58 ARP add for media successful for x.y.z.251
Aug  2 04:39:58 Table Id 0x1000000:Added ACTIVE ROUTE x.y.z.254 index 69 giaddr x.y.z.254
Aug  2 04:39:58 Updating SDB with client session state 2
Aug  2 04:39:58 Mirroring disabled (ha_graceful_switchover false) x.y.z.251
Aug  2 04:39:58 Allocated memory to store in SDB, sizeof(jdhcpd_ha_client_header_t) 88 + total_tlvs_len 85 (sizeof(client->session_id) is 11)
Aug  2 04:39:58 Saved NON-DEMUX interface name vlan.0 into mirroring ha_info
Aug  2 04:39:58 Added TLV for DHCPv4 CLIENT ID from client key, length 7
Aug  2 04:39:58 Added TLV for DHCPv6 LinkLocal Address, length 16
Aug  2 04:39:58 Marked SDB state ACTIVE, client in FSM state LOCAL_SERVER_STATE_WAIT_SDB_ACTIVE_MIRROR_REQ, SDB ACK will notify us for client session Id 11
Aug  2 04:39:58 Add route success for x.y.z.251
Aug  2 04:39:58 Successful SDB Mirrored Ack: returned code SDB_SUCCESS for session id 11
Aug  2 04:39:58 Received event 16 in state LOCAL_SERVER_STATE_WAIT_SDB_ACTIVE_MIRROR_REQ
Aug  2 04:39:58 SDB mirroring ACK received in state 24 (LOCAL_SERVER_STATE_WAIT_SDB_ACTIVE_MIRROR_REQ), changing state to LOCAL_SERVER_STATE_WAIT_SUBSCR_ADD
Aug  2 04:39:58 TESTPOINT: after SDB ACTIVE, ACK received in state LOCAL_SERVER_STATE_WAIT_SDB_ACTIVE_MIRROR_REQ
Aug  2 04:39:58 Received event CLIENT_EVENT_SUBSCRIBER_ACK in state LOCAL_SERVER_STATE_WAIT_SUBSCR_ADD
Aug  2 04:39:58 Found subunit stack in state LOCAL_SERVER_STATE_WAIT_SUBSCR_ADD
Aug  2 04:39:58 Set state to LOCAL_SERVER_STATE_BOUND
Aug  2 04:39:58 jdhcpd_pool_find: cfg 7d8000, pool_name 8caf10, DefaultPool
Aug  2 04:39:58 jdhcpd_pool_find: Pool DefaultPool  found 0x7e0800 w/ cfg container 0x7d8000
Aug  2 04:39:58 jdhcpd_pool_find: cfg 7d8000, pool_name 8caf10, DefaultPool
Aug  2 04:39:58 jdhcpd_pool_find: Pool DefaultPool  found 0x7e0800 w/ cfg container 0x7d8000
Aug  2 04:40:30 cid_l 7 cidp 1 mac 0x74 3e 2b M A C htype 1 subnet c0a803fe ifindx 0 key_type 1
Aug  2 04:40:30 Deleting ROUTE x.y.z.251
Aug  2 04:40:30 Delete route success for x.y.z.251
Aug  2 04:40:30 jdhcpd_rtsock_do_arp: L3ifindex (ifs) = 69, L2ifindex 0, addr_fam INET, operation 3
Aug  2 04:40:30 Delete media route for x.y.z.251 on interface vlan.0 (hw 74:3e:2b:M:A:C)
Aug  2 04:40:30 SUCCESSFULL Delete media route for x.y.z.251 on interface vlan.0 (hw 74:3e:2b:M:A:C), l2Int 
Aug  2 04:40:30 ARP delete for media successful for x.y.z.251
Aug  2 04:40:30 In state LOCAL_SERVER_STATE_WAIT_SUBSCR_DELETE, moving to LOCAL_SERVER_STATE_WAIT_AUTH_REQ_RELEASE
Aug  2 04:40:30 jdhcpd_auth_release: auth logout initiated for session 11 in state LOCAL_SERVER_STATE_WAIT_AUTH_REQ_RELEASE: flags1:0x10000; flags2:0x10
Aug  2 04:40:30 jdhcpd_auth_release_common: Release authd (request user logout): updating SDB with client session state SDB_SESSION_STATE_TERMINATING
Aug  2 04:40:30 jdhcpd_session_db_client_session_state_set: setting session state 3
Aug  2 04:40:30 Before adding to teardown list, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:40:30 LIST IS EMPTY, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:40:30 Added client w/ session_id 11 in state LOCAL_SERVER_STATE_WAIT_AUTH_REQ_RELEASE, to authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:40:30 jdhcpd_auth_release_common: auth logout requested for session 11 in state LOCAL_SERVER_STATE_WAIT_AUTH_REQ_RELEASE: flags1:0x10000; flags2:0x10
Aug  2 04:40:30 jdhcpd_user_logout_rply_proc: Got reply for user_logout, reply rqst_id = 65549 (session Id 11)
Aug  2 04:40:30 Get SDB STATE for session Id 11
Aug  2 04:40:30 Got SDB SESSION STATE 3 for client session Id 11
Aug  2 04:40:30 jdhcpd_user_logout_rply_proc: Got authd reply for user_logout in SDB state TERMINATING, for client x.y.z.251, client session Id 11
Aug  2 04:40:30 Request to remove client w. session_id 11 in state 4 from authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:40:30 Removed LAST client from authd_restart teardown list, zero out COUNT authd_restart_teardown_count
Aug  2 04:40:30 In state LOCAL_SERVER_STATE_WAIT_SDB_TERMINATED_MIRROR_REQ: starting updating SDB with client session state SDB_SESSION_STATE_TERMINATED
Aug  2 04:40:30 In client state LOCAL_SERVER_STATE_WAIT_SDB_TERMINATED_MIRROR_REQ: updating SDB with client session state SDB_SESSION_STATE_TERMINATED
Aug  2 04:40:30 jdhcpd_session_db_client_session_state_set: setting session state 4
Aug  2 04:40:30 Set to LOCAL_SERVER_STATE_WAIT_SDB_TERMINATED_MIRROR_REQ, SDB ACK will notify us for LOCAL-SERVER client session Id 11
Aug  2 04:40:31 Successful SDB Mirrored Ack: returned code SDB_SUCCESS for session id 11
Aug  2 04:40:31 LOCAL-SERVER: SDB mirroring ACK received in client state 26
Aug  2 04:40:31 jdhcpd_auth_release_terminated: auth terminate initiated for session 11: flags1:0x10000; flags2:0x10
Aug  2 04:40:31 Before adding to teardown list, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:40:31 LIST IS EMPTY, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:40:31 Added client w/ session_id 11 in state LOCAL_SERVER_STATE_WAIT_CM_DELETE, to authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:40:31 jdhcpd_auth_release_terminated_common: auth terminate request for session 11: flags1:0x10000; flags2:0x10
Aug  2 04:40:31 jdhcpd_user_terminated_rply_proc: Got user-session terminated REPLY from authd- reqId 65549, for client session Id 11
Aug  2 04:40:31 Request to remove client w. session_id 11 in state 5 from authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:40:31 Removed LAST client from authd_restart teardown list, zero out COUNT authd_restart_teardown_count
Aug  2 04:40:31 ATTEMPT TO DELETE libstats; session_id = 11

Aug  2 04:40:31 libstats delete stats: passed session_id 11

Aug  2 04:40:31 Deletion of baseline stats for session id 11 succeeded (client index 65549)
Aug  2 04:40:35 cid_l 7 cidp 1 mac 0x74 3e 2b M A C htype 1 subnet c0a803fe ifindx 0 key_type 1
Aug  2 04:40:35 DHCPv4 client entry ADDED, app_type 1 routing context default:default
Aug  2 04:40:35 Service_get NO service name
Aug  2 04:40:35 jdhcpd_sdb_create_session: phy device name vlan
Aug  2 04:40:35 jdhcpd_sdb_create_session: INET, setting LR name  default
Aug  2 04:40:35 jdhcpd_sdb_create_session: INET, setting RI name  default
Aug  2 04:40:35 jdhcpd_sdb_create_session: INET, setting underlying interface vlan.0
Aug  2 04:40:35 jdhcpd_sdb_create_session: INET, setting logical if name  vlan.0
Aug  2 04:40:35 jdhcpd_sdb_create_session: INET, setting MAC addr 74:3e:2b:M:A:C
Aug  2 04:40:35 jdhcpd_session_db_create_v4_session_attributes: INET, setting IP addr Key x.y.z.254
Aug  2 04:40:35 jdhcpd_session_db_create_v4_session_attributes: INET, dhcp_options len  41, data 35 01 01 3d 07 01 74 3e 2b M A C 3c 0c 75 64 68 63 70 20 31 2e 31 35 2e 32 39 02 02 40 37 09 01 02 03 06 0f 1c 2a 2b 2c
Aug  2 04:40:35 jdhcpd_sdb_create_session: session DB create got INET entry id 12
Aug  2 04:40:35 Mirroring disabled (ha_graceful_switchover false) 0.0.0.0
Aug  2 04:40:35 Allocated memory to store in SDB, sizeof(jdhcpd_ha_client_header_t) 88 + total_tlvs_len 85 (sizeof(client->session_id) is 12)
Aug  2 04:40:35 Saved NON-DEMUX interface name vlan.0 into mirroring ha_info
Aug  2 04:40:35 Added TLV for DHCPv4 CLIENT ID from client key, length 7
Aug  2 04:40:35 Added TLV for DHCPv6 LinkLocal Address, length 16
Aug  2 04:40:35 Successfully saved private data for 0.0.0.0 in SDB, length 173
Aug  2 04:40:35 SDB ACK will notify us for LOCAL-SERVER client session Id 12
Aug  2 04:40:36 Successful SDB Mirrored Ack: returned code SDB_SUCCESS for session id 12
Aug  2 04:40:36 SDB mirroring ACK received
Aug  2 04:40:36 jdhcpd_auth_request: auth-request attempt initiated for session 12: flags1:0x20000; flags2:0
Aug  2 04:40:36 Before adding to teardown list, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:40:36 LIST IS EMPTY, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:40:36 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
Aug  2 04:40:36 using Service-Profile-Name 
Aug  2 04:40:36 jdhcpd_authenticate_rply_proc: Got reply for auth_request, reply auth_rqst_id = 65550- (session Id 12)
Aug  2 04:40:36 Request to remove client w. session_id 12 in state 1 from authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:40:36 Removed LAST client from authd_restart teardown list, zero out COUNT authd_restart_teardown_count
Aug  2 04:40:36 jdhcpd_authenticate_rply_proc: auth login reply status for session 12: result:1; flags1:0x20000; flags2:0x10
Aug  2 04:40:36 got IP address jdhcpd_session_db_client_info_get x.y.z.251
Aug  2 04:40:36 got net mask jdhcpd_session_db_client_info_get 255.255.255.0
Aug  2 04:40:36 got ls name jdhcpd_session_db_client_info_get default
Aug  2 04:40:36 got ri name jdhcpd_session_db_client_info_get default
Aug  2 04:40:36 got pool name DefaultPool, len 11
Aug  2 04:40:36 Auth request reply ip x.y.z.251, pool name DefaultPool
Aug  2 04:40:36 jdhcpd_pool_find: cfg 7d8000, pool_name 8caf10, DefaultPool
Aug  2 04:40:36 jdhcpd_pool_find: Pool DefaultPool  found 0x7e0800 w/ cfg container 0x7d8000
Aug  2 04:40:36 jdhcpd_pool_find: cfg 7d8000, pool_name 8caf10, DefaultPool
Aug  2 04:40:36 jdhcpd_pool_find: Pool DefaultPool  found 0x7e0800 w/ cfg container 0x7d8000
Aug  2 04:40:36 Got AUTH reply- no profile- LOCAL_SERVER_STATE_CLIENT_SELECTING, updating SDB with client session state SDB_SESSION_STATE_CONFIGURED
Aug  2 04:40:36 jdhcpd_session_db_client_session_state_set: setting session state 1
Aug  2 04:40:36 jdhcpd_session_db_client_session_state_set: setting action bits to 3, config-bits:0x2 0 0 0 0 
Aug  2 04:40:36 libstats clear iflstats passed session_id 12

Aug  2 04:40:41 cid_l 7 cidp 1 mac 0x74 3e 2b M A C htype 1 subnet c0a803fe ifindx 0 key_type 1
Aug  2 04:40:41 jdhcpd_pool_find: cfg 7d8000, pool_name 8caf10, DefaultPool
Aug  2 04:40:41 jdhcpd_pool_find: Pool DefaultPool  found 0x7e0800 w/ cfg container 0x7d8000
Aug  2 04:40:41 jdhcpd_pool_find: cfg 7d8000, pool_name 8caf10, DefaultPool
Aug  2 04:40:41 jdhcpd_pool_find: Pool DefaultPool  found 0x7e0800 w/ cfg container 0x7d8000
Aug  2 04:41:14 ifd-event ge-0/0/15, oper change, state up, dev_index 149
Aug  2 04:42:45 Client 74-3e-2b-M-A-C stale timer expired for client-type 1
Aug  2 04:42:45 jdhcpd_auth_release: auth logout initiated for session 12 in state LOCAL_SERVER_STATE_WAIT_AUTH_REQ_RELEASE: flags1:0x20000; flags2:0x10
Aug  2 04:42:45 jdhcpd_auth_release_common: Release authd (request user logout): updating SDB with client session state SDB_SESSION_STATE_TERMINATING
Aug  2 04:42:45 jdhcpd_session_db_client_session_state_set: setting session state 3
Aug  2 04:42:45 jdhcpd_auth_release_common: ISSU logout for client session id 12, decrement global in-flight logins (count is 0)
Aug  2 04:42:45 Before adding to teardown list, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:42:45 LIST IS EMPTY, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:42:45 Added client w/ session_id 12 in state LOCAL_SERVER_STATE_WAIT_AUTH_REQ_RELEASE, to authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:42:45 jdhcpd_auth_release_common: auth logout requested for session 12 in state LOCAL_SERVER_STATE_WAIT_AUTH_REQ_RELEASE: flags1:0; flags2:0x10
Aug  2 04:42:45 jdhcpd_user_logout_rply_proc: Got reply for user_logout, reply rqst_id = 65550 (session Id 12)
Aug  2 04:42:45 Get SDB STATE for session Id 12
Aug  2 04:42:45 Got SDB SESSION STATE 3 for client session Id 12
Aug  2 04:42:45 jdhcpd_user_logout_rply_proc: Got authd reply for user_logout in SDB state TERMINATING, for client x.y.z.251, client session Id 12
Aug  2 04:42:45 Request to remove client w. session_id 12 in state 4 from authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:42:45 Removed LAST client from authd_restart teardown list, zero out COUNT authd_restart_teardown_count
Aug  2 04:42:45 In state LOCAL_SERVER_STATE_WAIT_SDB_TERMINATED_MIRROR_REQ: starting updating SDB with client session state SDB_SESSION_STATE_TERMINATED
Aug  2 04:42:45 In client state LOCAL_SERVER_STATE_WAIT_SDB_TERMINATED_MIRROR_REQ: updating SDB with client session state SDB_SESSION_STATE_TERMINATED
Aug  2 04:42:45 jdhcpd_session_db_client_session_state_set: setting session state 4
Aug  2 04:42:45 Set to LOCAL_SERVER_STATE_WAIT_SDB_TERMINATED_MIRROR_REQ, SDB ACK will notify us for LOCAL-SERVER client session Id 12
Aug  2 04:42:46 Successful SDB Mirrored Ack: returned code SDB_SUCCESS for session id 12
Aug  2 04:42:46 LOCAL-SERVER: SDB mirroring ACK received in client state 26
Aug  2 04:42:46 jdhcpd_auth_release_terminated: auth terminate initiated for session 12: flags1:0; flags2:0x10
Aug  2 04:42:46 Before adding to teardown list, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:42:46 LIST IS EMPTY, jdhcpd_inst.authd_restart_teardown_count 0
Aug  2 04:42:46 Added client w/ session_id 12 in state LOCAL_SERVER_STATE_WAIT_CM_DELETE, to authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:42:46 jdhcpd_auth_release_terminated_common: auth terminate request for session 12: flags1:0; flags2:0x10
Aug  2 04:42:46 jdhcpd_user_terminated_rply_proc: Got user-session terminated REPLY from authd- reqId 65550, for client session Id 12
Aug  2 04:42:46 Request to remove client w. session_id 12 in state 5 from authd_restart_teardown list, count authd_restart_teardown_count 1
Aug  2 04:42:46 Removed LAST client from authd_restart teardown list, zero out COUNT authd_restart_teardown_count
Aug  2 04:42:46 ATTEMPT TO DELETE libstats; session_id = 12
Aug  2 04:42:46 libstats delete stats: passed session_id 12
Aug  2 04:42:46 Deletion of baseline stats for session id 12 succeeded (client index 65550)

[michael_brado]

Sorry friend, what you're trying to do is all experimental.  Unleashed is designed to work on one LAN, no VLANs, despite your creative DHCP approach.
What's an "SRX"?

[klui]

OK, thanks.

SRX is a Juniper SRX firewall. I have the older SRX 240.