[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:42) Starting DHCP Server on interface br-zap
[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:64) DHCP Interface: br-zap
[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:65) DHCP Server Address: 100.88.66.1 - 64584201
[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:66) DHCP Port: 67
[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:67) DHCP Lease Time: 00000258
[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:68) DHCP Gateway: 100.88.66.1 - 64584201
[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:69) DHCP Subnet: 255.255.255.0 - ffffff00
[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:70) DHCP DNS1: 8.8.8.8 - 08080808
[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:71) DHCP DNS2: 8.8.4.4 - 08080404
[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:72) DHCP Start Address: 100.88.66.2 - 64584202
[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:73) DHCP End Address: 100.88.66.254 - 645842fe
[6][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:74) DHCP Next Pool Address: 100.88.66.2 - 64584202
[7][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:107) DHCP: Entering main dhcp loop
[7][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:118) memset()
[7][Fri Aug 3 22:37:12 2018][2784](dhcp_server.c:120) DHCP: recvfrom()
[5][Fri Aug 3 22:37:12 2018][2784](httpd_thread.c:58) Waiting for connections
[5][Fri Aug 3 22:37:12 2018][2784](httpd_thread.c:58) Waiting for connections
[5][Fri Aug 3 22:37:12 2018][2784](httpd_thread.c:58) Waiting for connections
[7][Fri Aug 3 22:37:13 2018][2784](simple_http.c:124) HTTP Response from Server: [HTTP/1.1 200 OK
Pong
Command: iptables -A Zappie_br-zap_Global -d 125.236.xx.xx/32 -j REJECT;iptables -A Zappie_br-eap_Global -d 125.236.xx.xx/32 -j REJECT;ntpd -q -p us.pool.ntp.org;
]
[6][Fri Aug 3 22:37:13 2018][2784](ping_thread.c:240) Auth Server Says: Pong
[6][Fri Aug 3 22:37:13 2018][2784](ping_thread.c:101) Auth server returned command: iptables -A Zappie_br-zap_Global -d 125.236.xx.xx/32 -j REJECT;iptables -A Zappie_br-eap_Global -d 125.236.xx.xx/32 -j REJECT;ntpd -q -p us.pool.ntp.org;
[6][Fri Aug 3 22:37:13 2018][2784](ping_thread.c:248) Executing Command: iptables -A Zappie_br-zap_Global -d 125.236.xx.xx/32 -j REJECT;iptables -A Zappie_br-eap_Global -d 125.236.xx.xx/32 -j REJECT;ntpd -q -p us.pool.ntp.org;
[7][Fri Aug 3 22:37:13 2018][2784](util.c:126) Waiting for PID 3377 to exit
[7][Fri Aug 3 22:37:13 2018][2784](util.c:135) Process PID 3377 exited
[7][Fri Aug 3 22:37:13 2018][2784](util.c:137) Exit status for PID 3377 is 0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:122) DHCP: 300 bytes received
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:135) DHCP: Queue the message for the msg_handlers to process
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:118) memset()
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:178) DHCP: Processing request
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:179) DHCP: handle_msg
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:152) DHCP: Message type: packet->op dec=1, hex=01
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:153) DHCP: Hardware type: packet->htype dec=1, hex=01
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:154) DHCP: Hardware address length: packet->hlen dec=6, hex=06
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:155) DHCP: Hops: packet->hops dec=0, hex=00
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:156) DHCP: Transaction ID: packet->xid dec=1468000759, hex=577fe9f7
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:120) DHCP: recvfrom()
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:157) DHCP: Seconds elapsed: packet->secs dec=0, hex=0000
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:158) DHCP: Bootp flags: packet->flags dec=0, hex=0000
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:159) DHCP: Client IP address: packet->ciaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:160) DHCP: Your Client IP address: packet->yiaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:161) DHCP: Next server IP address: packet->siaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:162) DHCP: Relay agent IP address: packet->giaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:163) DHCP: Client MAC address: packet->chaddr=90,3c,92,45,89,e2
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:164) DHCP: Client hardware address padding: packet->chaddr=0,0,0,0,0,0,0,0,0,0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:165) DHCP: Server host name: packet->sname=
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:166) DHCP: Boot file name: packet->file=
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:173) DHCP: Magic cookie dec=1669485411, hex=63825363
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=53
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=1
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=55
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=7
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=57
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=2
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=61
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=7
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=51
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=4
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=12
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=14
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=255
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:279) DHCP: dhcp option padding null
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:195) DHCP: Starting request
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:271) DHCP: Dispatching packet
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:303) DHCP packet type 1 received
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:308) DHCP: Received discover packet, preparing responce
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:336) DHCP: Do discover
[6][Fri Aug 3 22:38:09 2018][2784](ip_allocator.c:45) DHCP: Client mac is: 90:3c:92:45:89:e2
[7][Fri Aug 3 22:38:09 2018][2784](ip_allocator.c:49) Locking client list
[7][Fri Aug 3 22:38:09 2018][2784](ip_allocator.c:49) Client list locked
[7][Fri Aug 3 22:38:09 2018][2784](ip_allocator.c:98) DHCP: Mac 90:3c:92:45:89:e2 doesnt yet have an address assigned
[7][Fri Aug 3 22:38:09 2018][2784](ip_allocator.c:26) DHCP: Converting to IP string from 64584202
[7][Fri Aug 3 22:38:09 2018][2784](ip_allocator.c:125) DHCP: Checking if we can use: 100.88.66.2 [64584202]
[6][Fri Aug 3 22:38:09 2018][2784](ip_allocator.c:157) DHCP: Allocating 100.88.66.2 [64584202] to 90:3c:92:45:89:e2
[7][Fri Aug 3 22:38:09 2018][2784](ip_allocator.c:159) DHCP: Adding to client list and checking if client mac is on auth server whitelist
[7][Fri Aug 3 22:38:09 2018][2784](http.c:314) Adding new dhcp client 100.88.66.2 [90:3c:92:45:89:e2] on interface br-zap
[6][Fri Aug 3 22:38:09 2018][2784](client_list.c:145) Added a new client to linked list 100.88.66.2 [90:3c:92:45:89:e2] on br-zap Token: unauthenticated
[7][Fri Aug 3 22:38:09 2018][2784](auth.c:183) Unlocking client list
[7][Fri Aug 3 22:38:09 2018][2784](auth.c:183) Client list unlocked
[7][Fri Aug 3 22:38:09 2018][2784](centralserver.c:321) Locking config
[7][Fri Aug 3 22:38:09 2018][2784](centralserver.c:321) Config locked
[7][Fri Aug 3 22:38:09 2018][2784](centralserver.c:371) Level 1: Calculated 1 auth servers in list
[7][Fri Aug 3 22:38:09 2018][2784](centralserver.c:387) Level 1: Resolving auth server [www.zappie.net]
[7][Fri Aug 3 22:38:09 2018][2784](util.c:185) Locking wd_gethostbyname()
[7][Fri Aug 3 22:38:09 2018][2784](util.c:185) wd_gethostbyname() locked
[7][Fri Aug 3 22:38:09 2018][2784](util.c:198) Unlocking wd_gethostbyname()
[7][Fri Aug 3 22:38:09 2018][2784](util.c:198) wd_gethostbyname() unlocked
[7][Fri Aug 3 22:38:09 2018][2784](centralserver.c:446) Level 1: Resolving auth server [www.zappie.net] succeeded = [120.138.19.19]
[7][Fri Aug 3 22:38:09 2018][2784](centralserver.c:484) Level 1: Connecting to auth server www.zappie.net:80
[7][Fri Aug 3 22:38:09 2018][2784](centralserver.c:513) Level 1: Successfully connected to auth server www.zappie.net:80
[7][Fri Aug 3 22:38:09 2018][2784](centralserver.c:323) Unlocking config
[7][Fri Aug 3 22:38:09 2018][2784](centralserver.c:323) Config unlocked
[7][Fri Aug 3 22:38:09 2018][2784](centralserver.c:329) Connected to auth server
[6][Fri Aug 3 22:38:09 2018][2784](http.c:72) Hashing sha256 parameter [6D5BC50490127A1A6013F34D24CD739E844D7B8EE2F19B1D4FD3F3XXXXXXXX]
[7][Fri Aug 3 22:38:09 2018][2784](simple_http.c:77) Sending HTTP request to auth server: [GET /control/aaa_zappie.php?stage=framed&type=notset&zone_id=91&router_id=186&router_ip=100.88.66.1&router_mac=C2-56-27-CB-8E-FA&client_ip=100.88.66.2&client_mac=90-3C-92-45-89-E2&incoming=0&outgoing=0&sessionid=unauthenticated&sh=6D5BC50490127A1A6013F34D24CD739XXXXXX HTTP/1.0
User-Agent: Zappie Gateway 1.3.0
Host: www.zappie.net
]
[7][Fri Aug 3 22:38:09 2018][2784](simple_http.c:87) Reading response
[7][Fri Aug 3 22:38:09 2018][2784](simple_http.c:111) Read 479 bytes
[7][Fri Aug 3 22:38:09 2018][2784](simple_http.c:124) HTTP Response from Server: [HTTP/1.1 200 OK
Auth: 9 - AUTH_MACDENIED
Reason: Insufficient prepaid data remaining]
[6][Fri Aug 3 22:38:09 2018][2784](centralserver.c:83) Auth server returned authentication code 9
[7][Fri Aug 3 22:38:09 2018][2784](auth.c:198) Locking client list
[7][Fri Aug 3 22:38:09 2018][2784](auth.c:198) Client list locked
[6][Fri Aug 3 22:38:09 2018][2784](auth.c:274) Got AUTH_MACDENIED while mac authenticating 100.88.66.2 [90:3c:92:45:89:e2]
[7][Fri Aug 3 22:38:09 2018][2784](ip_allocator.c:172) Unlocking client list
[7][Fri Aug 3 22:38:09 2018][2784](ip_allocator.c:172) Client list unlocked
[7][Fri Aug 3 22:38:09 2018][2784](ip_allocator.c:174) DHCP: Allocate IP address: 64584202
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:18) DHCP: Adding option 53, hex value=02
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:42) DHCP: Adding option 54, hex value=64584201
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:42) DHCP: Adding option 51, hex value=00000258
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:42) DHCP: Adding option 3, hex value=64584201
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:42) DHCP: Adding option 1, hex value=ffffff00
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:68) DHCP: Adding option 6, hex value1=08080808, hex value2=08080404
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:322) DHCP: serialize, packet=-1225737552
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:359) DHCP: Message type: packet->op dec=2, hex=02
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:360) DHCP: Hardware type: packet->htype dec=1, hex=01
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:361) DHCP: Hardware address length: packet->hlen dec=6, hex=06
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:362) DHCP: Hops: packet->hops dec=1, hex=01
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:363) DHCP: Transaction ID: packet->xid dec=1468000759, hex=577fe9f7
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:364) DHCP: Seconds elapsed: packet->secs dec=0, hex=0000
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:365) DHCP: Bootp flags: packet->flags dec=0, hex=0000
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:366) DHCP: Client IP address: packet->ciaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:367) DHCP: Your Client IP address: packet->yiaddr dec=1683505666, hex=64584202
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:368) DHCP: Next server IP address: packet->siaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:369) DHCP: Relay agent IP address: packet->giaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:370) DHCP: Client MAC address: packet->chaddr=90,3c,92,45,89,e2
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:371) DHCP: Client hardware address padding: packet->chaddr=0,0,0,0,0,0,0,0,0,0
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:372) DHCP: Server host name: packet->sname=
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:373) DHCP: Boot file name: packet->file=
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:398) DHCP: Magic cookie dec=1669485411, hex=63825363
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=53, length=1
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=54, length=4
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=51, length=4
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=3, length=4
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=1, length=4
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=6, length=8
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:428) DHCP: total 300 bytes writen
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:210) DHCP: Setting unicast ip to response->yiaddr 64584202
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:227) DHCP: Unicasting packet to [90:3c:92:45:89:e2] 64584202
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_send.c:101) DHCP: Interface br-zap, MAC address [c2:56:27:cb:8e:fa]
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_send.c:110) DHCP: Index for interface br-zap is 15
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:247) DHCP: Sent 300 bytes
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:248) DHCP: Freeing response packet from memory
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:290) DHCP: free_packet, packet=-1225737552
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:256) DHCP: Freeing request packet from memory
[6][Fri Aug 3 22:38:09 2018][2784](dhcp_packet.c:290) DHCP: free_packet, packet=-1226359088
[7][Fri Aug 3 22:38:09 2018][2784](dhcp_server.c:263) DHCP: Freeing message packet form memory
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:122) DHCP: 300 bytes received
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:135) DHCP: Queue the message for the msg_handlers to process
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:118) memset()
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:120) DHCP: recvfrom()
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:178) DHCP: Processing request
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:179) DHCP: handle_msg
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:152) DHCP: Message type: packet->op dec=1, hex=01
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:153) DHCP: Hardware type: packet->htype dec=1, hex=01
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:154) DHCP: Hardware address length: packet->hlen dec=6, hex=06
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:155) DHCP: Hops: packet->hops dec=0, hex=00
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:156) DHCP: Transaction ID: packet->xid dec=1468000759, hex=577fe9f7
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:157) DHCP: Seconds elapsed: packet->secs dec=1, hex=0001
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:158) DHCP: Bootp flags: packet->flags dec=0, hex=0000
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:159) DHCP: Client IP address: packet->ciaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:160) DHCP: Your Client IP address: packet->yiaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:161) DHCP: Next server IP address: packet->siaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:162) DHCP: Relay agent IP address: packet->giaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:163) DHCP: Client MAC address: packet->chaddr=90,3c,92,45,89,e2
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:164) DHCP: Client hardware address padding: packet->chaddr=0,0,0,0,0,0,0,0,0,0
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:165) DHCP: Server host name: packet->sname=
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:166) DHCP: Boot file name: packet->file=
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:173) DHCP: Magic cookie dec=1669485411, hex=63825363
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=53
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=1
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=55
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=7
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=57
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=2
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=61
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=7
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=50
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=4
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=54
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=4
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=12
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=14
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=255
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:218) DHCP: dhcp option length=0
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:198) DHCP: dhcp option code=0
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:279) DHCP: dhcp option padding null
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:195) DHCP: Starting request
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:271) DHCP: Dispatching packet
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:303) DHCP packet type 3 received
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:320) DHCP: Received request packet, preparing responce
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:413) DHCP: Do request
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:428) DHCP: request->ciaddr=0x00000000, get request_address from dhcp option
[6][Fri Aug 3 22:38:10 2018][2784](ip_allocator.c:45) DHCP: Client mac is: 90:3c:92:45:89:e2
[7][Fri Aug 3 22:38:10 2018][2784](ip_allocator.c:49) Locking client list
[7][Fri Aug 3 22:38:10 2018][2784](ip_allocator.c:49) Client list locked
[7][Fri Aug 3 22:38:10 2018][2784](ip_allocator.c:165) DHCP: found client 100.88.66.2 [90:3c:92:45:89:e2] in client list on interface br-zap
[7][Fri Aug 3 22:38:10 2018][2784](ip_allocator.c:172) Unlocking client list
[7][Fri Aug 3 22:38:10 2018][2784](ip_allocator.c:172) Client list unlocked
[7][Fri Aug 3 22:38:10 2018][2784](ip_allocator.c:174) DHCP: Allocate IP address: 64584202
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:18) DHCP: Adding option 53, hex value=05
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:42) DHCP: Adding option 54, hex value=64584201
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:42) DHCP: Adding option 51, hex value=00000258
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:42) DHCP: Adding option 3, hex value=64584201
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:42) DHCP: Adding option 1, hex value=ffffff00
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:68) DHCP: Adding option 6, hex value1=08080808, hex value2=08080404
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:322) DHCP: serialize, packet=-1225737552
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:359) DHCP: Message type: packet->op dec=2, hex=02
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:360) DHCP: Hardware type: packet->htype dec=1, hex=01
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:361) DHCP: Hardware address length: packet->hlen dec=6, hex=06
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:362) DHCP: Hops: packet->hops dec=1, hex=01
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:363) DHCP: Transaction ID: packet->xid dec=1468000759, hex=577fe9f7
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:364) DHCP: Seconds elapsed: packet->secs dec=0, hex=0000
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:365) DHCP: Bootp flags: packet->flags dec=0, hex=0000
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:366) DHCP: Client IP address: packet->ciaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:367) DHCP: Your Client IP address: packet->yiaddr dec=1683505666, hex=64584202
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:368) DHCP: Next server IP address: packet->siaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:369) DHCP: Relay agent IP address: packet->giaddr dec=0, hex=00000000
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:370) DHCP: Client MAC address: packet->chaddr=90,3c,92,45,89,e2
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:371) DHCP: Client hardware address padding: packet->chaddr=0,0,0,0,0,0,0,0,0,0
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:372) DHCP: Server host name: packet->sname=
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:373) DHCP: Boot file name: packet->file=
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:398) DHCP: Magic cookie dec=1669485411, hex=63825363
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=53, length=1
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=54, length=4
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=51, length=4
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=3, length=4
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=1, length=4
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:406) DHCP: add to buffer, dhcp option code=6, length=8
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:428) DHCP: total 300 bytes writen
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:210) DHCP: Setting unicast ip to response->yiaddr 64584202
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:227) DHCP: Unicasting packet to [90:3c:92:45:89:e2] 64584202
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_send.c:101) DHCP: Interface br-zap, MAC address [c2:56:27:cb:8e:fa]
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_send.c:110) DHCP: Index for interface br-zap is 15
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:247) DHCP: Sent 300 bytes
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:248) DHCP: Freeing response packet from memory
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:290) DHCP: free_packet, packet=-1225737552
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:256) DHCP: Freeing request packet from memory
[6][Fri Aug 3 22:38:10 2018][2784](dhcp_packet.c:290) DHCP: free_packet, packet=-1226359088
[7][Fri Aug 3 22:38:10 2018][2784](dhcp_server.c:263) DHCP: Freeing message packet form memory
[7][Fri Aug 3 22:38:12 2018][2784](auth.c:81) Unlock pthread_mutex_unlock()
[7][Fri Aug 3 22:38:12 2018][2784](auth.c:84) Running fw_sync_with_authserver()
[7][Fri Aug 3 22:38:12 2018][2784](firewall.c:495) Locking client list
[7][Fri Aug 3 22:38:12 2018][2784](firewall.c:495) Client list locked
[7][Fri Aug 3 22:38:12 2018][2784](firewall.c:544) No authenticated clients found in client list - not processing counters lists
[7][Fri Aug 3 22:38:12 2018][2784](firewall.c:553) Unlocking client list
[7][Fri Aug 3 22:38:12 2018][2784](firewall.c:553) Client list unlocked
[6][Fri Aug 3 22:38:12 2018][2784](firewall.c:583) Checking unauthenticated client 100.88.66.2 [90:3c:92:45:89:e2] for dhcp timeout - removing in 898 seconds
[7][Fri Aug 3 22:38:12 2018][2784](firewall.c:764) Clearing worklist client_list_destroy()
[7][Fri Aug 3 22:38:12 2018][2784](auth.c:86) Finished running fw_sync_with_authserver()
[6][Fri Aug 3 22:38:34 2018][2784](http.c:72) Hashing sha256 parameter [FBA5D71368AA95A1D5AC177A7DE5F09280XXXXXXX]
[7][Fri Aug 3 22:38:34 2018][2784](simple_http.c:77) Sending HTTP request to auth server: [GET /control/aaa_zappie.php?stage=login&type=notset&zone_id=91&router_id=186&router_ip=100.88.66.1&router_mac=C2-56-27-CB-8E-FA&client_ip=100.88.66.2&client_mac=90-3C-92-45-89-E2&incoming=0&outgoing=0&sessionid=p_12126195b64d96acca70&sh=FBA5D71368AA95A1D5AC177A7DE5F09280C5BF3CFB300542XXXXXX HTTP/1.0
User-Agent: Zappie Gateway 1.3.0
Host: www.zappie.net
]
[7][Fri Aug 3 22:38:34 2018][2784](simple_http.c:87) Reading response
[7][Fri Aug 3 22:38:35 2018][2784](simple_http.c:111) Read 536 bytes
[7][Fri Aug 3 22:38:35 2018][2784](simple_http.c:124) HTTP Response from Server: [HTTP/1.1 200 OK
Auth: 1 - AUTH_ALLOWED
CodeUser: 1
Bytes: 524288000
Time: 86400
BwDown: 10240
BwUp: 10240
Profile: 1
]
[6][Fri Aug 3 22:38:35 2018][2784](centralserver.c:83) Auth server returned authentication code 1
[6][Fri Aug 3 22:38:35 2018][2784](centralserver.c:99) Auth server returned profile code 1
[6][Fri Aug 3 22:38:35 2018][2784](centralserver.c:135) Auth server returned max download bandwidth in kbits 10240
[6][Fri Aug 3 22:38:35 2018][2784](centralserver.c:153) Auth server returned max upload bandwidth in kbits 10240
[6][Fri Aug 3 22:38:35 2018][2784](centralserver.c:171) Auth server returned max bytes 524288000
[6][Fri Aug 3 22:38:35 2018][2784](centralserver.c:189) Auth server returned max time 86400
[6][Fri Aug 3 22:38:35 2018][2784](centralserver.c:117) Auth server returned CodeUser 1
[7][Fri Aug 3 22:38:35 2018][2784](centralserver.c:297) Login type set to 1
[7][Fri Aug 3 22:38:35 2018][2784](auth.c:351) Locking client list
[7][Fri Aug 3 22:38:35 2018][2784](auth.c:351) Client list locked
[7][Fri Aug 3 22:38:35 2018][2784](auth.c:373) Setting login type to 1 for client 100.88.66.2 [90:3c:92:45:89:e2]
[7][Fri Aug 3 22:38:35 2018][2784](auth.c:377) Setting profile type to 1 for client 100.88.66.2 [90:3c:92:45:89:e2]
[7][Fri Aug 3 22:38:35 2018][2784](auth.c:382) Setting bandwidth shaping (download) to 10240 for client 100.88.66.2 [90:3c:92:45:89:e2]
[7][Fri Aug 3 22:38:35 2018][2784](auth.c:387) Setting bandwidth shaping (upload) to 10240 for client 100.88.66.2 [90:3c:92:45:89:e2]
[6][Fri Aug 3 22:38:35 2018][2784](auth.c:393) Setting maximum bytes to 524288000 for client 100.88.66.2 [90:3c:92:45:89:e2]
[6][Fri Aug 3 22:38:35 2018][2784](auth.c:398) Setting maximum time to 86400 for client 100.88.66.2 [90:3c:92:45:89:e2]
[6][Fri Aug 3 22:38:35 2018][2784](auth.c:474) Got AUTH_ALLOWED while authenticating client 100.88.66.2 [90:3c:92:45:89:e2] session id p_12126195b64d96acca70 - logging them in and redirecting back to portal
[7][Fri Aug 3 22:38:35 2018][2784](firewall.c:109) Allowing 100.88.66.2 [90:3c:92:45:89:e2] through the firewall with profile type 1
[7][Fri Aug 3 22:38:35 2018][2784](firewall.c:117) Setting auth_last_updated and counters.last_updated to current time 1533335915
[7][Fri Aug 3 22:38:35 2018][2784](fw_iptables.c:145) Executing command: iptables -t mangle -A Zappie_br-zap_Outgoing -s 100.88.66.2 -m mac --mac-source 90:3c:92:45:89:e2 -j MARK --set-mark 1
[7][Fri Aug 3 22:38:35 2018][2784](util.c:126) Waiting for PID 3449 to exit
[7][Fri Aug 3 22:38:35 2018][2784](util.c:135) Process PID 3449 exited
[7][Fri Aug 3 22:38:35 2018][2784](util.c:137) Exit status for PID 3449 is 0
[7][Fri Aug 3 22:38:35 2018][2784](fw_iptables.c:145) Executing command: iptables -t mangle -A Zappie_br-zap_Incoming -d 100.88.66.2 -j ACCEPT
[7][Fri Aug 3 22:38:35 2018][2784](util.c:126) Waiting for PID 3451 to exit
[7][Fri Aug 3 22:38:35 2018][2784](util.c:135) Process PID 3451 exited
[7][Fri Aug 3 22:38:35 2018][2784](util.c:137) Exit status for PID 3451 is 0
[7][Fri Aug 3 22:38:35 2018][2784](firewall.c:129) Adding bandwidth shaping for 100.88.66.2 [90:3c:92:45:89:e2] - 10240 down and 10240 up
[7][Fri Aug 3 22:38:35 2018][2784](bw_shaping.c:106) Executing command: tc class add dev br-zap parent 1:0 classid 1:2 htb rate 10240kbit burst 13k
[7][Fri Aug 3 22:38:35 2018][2784](util.c:126) Waiting for PID 3453 to exit
[7][Fri Aug 3 22:38:35 2018][2784](util.c:135) Process PID 3453 exited
[7][Fri Aug 3 22:38:35 2018][2784](util.c:137) Exit status for PID 3453 is 0
[7][Fri Aug 3 22:38:35 2018][2784](bw_shaping.c:106) Executing command: tc class add dev ifb0 parent 1:0 classid 1:2 htb rate 10240kbit burst 13k
[7][Fri Aug 3 22:38:35 2018][2784](util.c:126) Waiting for PID 3455 to exit
[7][Fri Aug 3 22:38:35 2018][2784](util.c:135) Process PID 3455 exited
[7][Fri Aug 3 22:38:35 2018][2784](util.c:137) Exit status for PID 3455 is 0
[7][Fri Aug 3 22:38:35 2018][2784](bw_shaping.c:106) Executing command: tc filter add dev br-zap parent 1: pref 5 handle 800::2 protocol ip u32 match ip dst 100.88.66.2/32 flowid 1:2
[7][Fri Aug 3 22:38:35 2018][2784](util.c:126) Waiting for PID 3457 to exit
[7][Fri Aug 3 22:38:35 2018][2784](util.c:135) Process PID 3457 exited
[7][Fri Aug 3 22:38:35 2018][2784](util.c:137) Exit status for PID 3457 is 0
[7][Fri Aug 3 22:38:35 2018][2784](bw_shaping.c:106) Executing command: tc filter add dev ifb0 parent 1: pref 5 handle 800::2 protocol ip u32 match ip src 100.88.66.2/32 flowid 1:2
[7][Fri Aug 3 22:38:35 2018][2784](util.c:126) Waiting for PID 3459 to exit
[7][Fri Aug 3 22:38:35 2018][2784](util.c:135) Process PID 3459 exited
[7][Fri Aug 3 22:38:35 2018][2784](util.c:137) Exit status for PID 3459 is 0
[6][Fri Aug 3 22:38:35 2018][2784](bw_shaping.c:516) Successfully setup bandwidth shaping 10.0/10.0 Mbps for client 100.88.66.2