Skip to content
New issue

Have a question about this project? Sign up for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “Sign up for GitHub”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? Sign in to your account

Slow loading of pages when navigating the web UI, dnsmasq signal 17 logged to FTL.log #2284

Open
fongd opened this issue Feb 19, 2025 · 49 comments

Comments

@fongd
Copy link

fongd commented Feb 19, 2025

Versions

  • Core version is v6.0.1 (Latest: v6.0.1)
  • Web version is v6.0 (Latest: v6.0)
  • FTL version is v6.0 (Latest: v6.0)

Platform

  • OS and version: Debian 12.9
  • Platform: VM running under Proxmox 8.3.3 on an Intel N5105 (1 GB RAM, 4.8 GB free disk space, 1 x CPU assigned to the VM)

Expected behavior

Navigating from page to page in the web UI should be fast.

Actual behavior / bug

The web UI is extremely slow to load pages. It takes between 25 to 30 seconds (!) between clicking any of the left menu items for the requested page to load. It's not just the Query Log page either—even just going to any of the settings pages results in the same lengthy delay. Filtering the Query Log and going page to page once it's loaded is actually fast, though.

When I enable debug logs, I see these messages being logged to FTL.log when I navigate from page to page:

2025-02-19 14:20:00.210 EST [530M] DEBUG_ANY: dnsmasq received signal 17
2025-02-19 14:20:00.231 EST [530M] DEBUG_ANY: dnsmasq received signal 17
2025-02-19 14:21:00.109 EST [530M] DEBUG_ANY: dnsmasq received signal 17
2025-02-19 14:21:00.127 EST [530M] DEBUG_ANY: dnsmasq received signal 17
2025-02-19 14:21:34.657 EST [530M] DEBUG_ANY: dnsmasq received signal 17

I'm pretty sure this has something to do with the interface's lack of responsiveness, however, I don't know where to start looking especially since my other Pi-hole instance is using a very similar Pi-hole configuration as this one but does not exhibit this behaviour.

Steps to reproduce

Steps to reproduce the behavior:

  1. Click any of the menu options in the left menu to go to any page in Pi-hole
  2. Wait
  3. Wait some more
  4. See the page load after 20+ seconds

Debug Token

Additional context

I am running another Pi-hole v6 instance on another server (that one is running Ubuntu with beefier hardware but still this N5105 should be more than adequate) that does not exhibit this behaviour.

Both of my Pi-hole instances were upgraded to v6 from v5.18.4. Only this one exhibits this problem, the other Pi-hole instance's web UI is snappy.

@sinofool
Copy link

My setup having similar issue, DNS unresponsive every 10s.
Give the VM more memory (512MB -> 2G) fixed it.

@Gadget-Guy
Copy link

I had the same problem, but increasing memory should not be necessary. Pi-Hole is hardly using any.

FWIW, I found that double clicking any of the left menu items provided an immediate response.

@fongd
Copy link
Author

fongd commented Feb 20, 2025

DNS resolution works fine, doesn't seem afflicted by whatever is causing the sluggishness of the web UI. The docs still suggest 512 MB of RAM as the base requirement (I am currently running 1 GB now), but I will try bumping up to 2 GB to see if that helps, especially since I am also running unbound in the same VM.

EDIT: Bumped to 2 GB of RAM, made no difference, so I've reverted back to 1 GB.

@yebo29
Copy link

yebo29 commented Feb 20, 2025

Updated to v6 this evening; had to pull it off my network. Pi 3B, very very poor UI and DNS performance. htop doesn't show much beyond persistent 3-5% from FTL, which makes sense. Reboots didn't help.

@yebo29
Copy link

yebo29 commented Feb 20, 2025

Also, can't get Homepage widget integration working. API key changed on me? Can't figure out where to set it.
Figured this out. Needed to add version: 6 to the widget config.

@prezmc
Copy link

prezmc commented Feb 21, 2025

Came to log this issue, but found one already here. I can confirm the same behaviors as listed above. I upgraded to v6, and noticed the change. In an attempt to resolve, I used teleporter backup, uninstalled pihole, clean install, and restored the backup. No improvement.

@svc-user
Copy link

Just voicing my voice with the same experience.

@seisfeld
Copy link

I made a fresh install for testing in a VM running Debian.

I have no clients pointing to this pi-hole instance, but I can confirm every page load takes 30s. BUT for me this only happens in Safari. Strange enough it works like charm in Chrome. Dunno if that helps.

@prezmc
Copy link

prezmc commented Feb 23, 2025

I tried doing safari, edge, and arc, all are slow. Edge seems slightly faster than the others. There is also a noticeable CPU utilization spike when attempting to load any page.

@Kryminal
Copy link

Kryminal commented Feb 24, 2025

Mine is running in an LXC container on Proxmox. Had issues with the update from v5 to v6, so I spun up a new container and installed v6 from scratch (Debian 12.7.1 as the base). The only issue I'm having is the cpu usage (shows up to 200% at times, but the load values are pretty close to the production v5 instance, so idk), and the ui slowness.

FWIW, I found that double clicking any of the left menu items provided an immediate response.

I get the same result using Safari

I made a fresh install for testing in a VM running Debian.

I have no clients pointing to this pi-hole instance, but I can confirm every page load takes 30s. BUT for me this only happens in Safari. Strange enough it works like charm in Chrome. Dunno if that helps.

I found the same. Really slow in Safari (except noted above) and links are instantaneous in Chrome.

edit to add versions and other info:
Core [v6.0.4]
FTL [v6.0.2]
Web interface [v6.0.1]
CPU: 2 cores allocated, under the hood it is a Xeon E5-2697 v3, shows 150% (3.01, 2.22, 2.31)
Memory: 128Gb on the server, 512mb allocated to the container for pi-hole, shows 7.3% usage on the system info in pi-hole web guide

@seisfeld
Copy link

seisfeld commented Feb 24, 2025

FWIW, I found that double clicking any of the left menu items provided an immediate response.
I get the same result using Safari

Ooooooh what a nice find. I can confirm the same. Double clicking any link in the side bar immediately loads the page in Safari for me as well. A single click though takes 30s. Again Chrome is instant with just a single click.

I don't observe any high cpu spike or anything on my VM. The machine is basically idling. So I assume this is somehow browser related...

Edit:

Core version is v6.0.4 (Latest: v6.0.4)
Web version is v6.0.1 (Latest: v6.0.1)
FTL version is v6.0.2 (Latest: v6.0.2)

@fongd
Copy link
Author

fongd commented Feb 24, 2025

Double clicking is only a partial workaround for Safari. The Query List, in particular, still takes many seconds to render the table if you do this even if the rest of the page has already loaded.

@seisfeld
Copy link

I can't check that because my install is fresh and I have no clients pointed to it. But worth to consider for sure!

@yubiuser
Copy link
Member

Could you please try the following

Settings > All Settings > Web Interface > webserver.headers > Add Access-Control-Allow-Origin: *

Do a full Pi-hole restart with systemctl restart pihole-FTL.service

@seisfeld
Copy link

I don't have webserver.headers on that page. Do I need to enable this elsewhere?

@yubiuser
Copy link
Member

Sorry, the code is not yet released. You need to checkout the development branch with

sudo pihole checkout ftl development

@seisfeld
Copy link

Okay I did that. Unfortunately no difference in Safari.

Core
Version is v6.0.4 (Latest: v6.0.4)
Branch is master
Hash is 567bb72 (Latest: 567bb72)
Web
Version is v6.0.1 (Latest: v6.0.1)
Branch is master
Hash is 42e7279a (Latest: 42e7279a)
FTL
Version is vDev-0df08a4 (Latest: null)
Branch is development
Hash is 0df08a4 (Latest: 0df08a4)

@yubiuser
Copy link
Member

A pitty. Thanks for trying. You can go back with

sudo pihole checkout ftl master

@seisfeld
Copy link

No problem! You are welcome. I can test everything you want if you have other ideas, this is just a VM explicitly setup for testing the new v6 with no production clients pointing to it.

@seisfeld
Copy link

I just made another interesting observation. When I use Safari, click on a link in side bar it "stalls" for 30s - so far so (not) good. But while in this state the machine also does not answer any other connections ie. to Chrome. Chrome stalls as well until the page loads in Safari and then continues normally. As if the process serving the pages is "busy". Dunno if that helps.

@yubiuser yubiuser transferred this issue from pi-hole/pi-hole Feb 25, 2025
@yubiuser
Copy link
Member

Transferring to FTL, as this is a webserver/API issue.

@yubiuser
Copy link
Member

Could you please enable 'debug.API' and post a log excerpt when it get stall from /var/log/pihole/FTL.log

@PromoFaux
Copy link
Member

Just for fun and games... please try experimenting with the webserver.threads setting.

By default it is set to be equal to the number of CPU cores minus one. Which is out of an abundance of caution to ensure the entire system doesn't become unrespsonsive.

Could you try increasing it in increments of 5? i.e try 5, if that doesn't make a difference try 10 etc etc etc...

@fongd
Copy link
Author

fongd commented Feb 25, 2025

Could you please enable 'debug.API' and post a log excerpt when it get stall from /var/log/pihole/FTL.log

Here's mine. I clicked on the Groups menu item immediately after enabling debug.API and here's what I captured:

2025-02-25 13:01:34.929 EST [545/T1762] INFO: Local URI: "/admin/groups"
2025-02-25 13:01:34.929 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 13:01:34.929 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 13:01:34 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 13:01:34.929 EST [545/T1762] DEBUG_API: Rewriting filename: /var/www/html/admin/groups
2025-02-25 13:01:34.929 EST [545/T1762] DEBUG_API: Rewriting Lua page: /var/www/html/admin/groups ==> /var/www/html/admin/groups.lp
2025-02-25 13:01:35.049 EST [545/T1762] INFO: Local URI: "/admin/vendor/bootstrap-select/bootstrap-select.min.css"
2025-02-25 13:01:35.049 EST [545/T1762] DEBUG_API: Rewriting filename: /var/www/html/admin/vendor/bootstrap-select/bootstrap-select.min.css
2025-02-25 13:01:35.075 EST [545/T1762] INFO: Local URI: "/admin/vendor/bootstrap-select/bootstrap-select.min.js"
2025-02-25 13:01:35.076 EST [545/T1762] DEBUG_API: Rewriting filename: /var/www/html/admin/vendor/bootstrap-select/bootstrap-select.min.js
2025-02-25 13:01:35.093 EST [545/T1762] INFO: Local URI: "/admin/scripts/js/groups.js"
2025-02-25 13:01:35.094 EST [545/T1762] DEBUG_API: Rewriting filename: /var/www/html/admin/scripts/js/groups.js
2025-02-25 13:01:35.116 EST [545/T1762] INFO: Local URI: "/admin/scripts/js/groups-common.js"
2025-02-25 13:01:35.117 EST [545/T1762] DEBUG_API: Rewriting filename: /var/www/html/admin/scripts/js/groups-common.js
2025-02-25 13:01:35.194 EST [545/T1762] INFO: Local URI: "/admin/vendor/bootstrap/fonts/glyphicons-halflings-regular.woff2"
2025-02-25 13:01:35.194 EST [545/T1762] DEBUG_API: Rewriting filename: /var/www/html/admin/vendor/bootstrap/fonts/glyphicons-halflings-regular.woff2
2025-02-25 13:01:35.223 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/groups ? _=1740506495020 (Content-Type (null))
2025-02-25 13:01:35.224 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 13:01:35.224 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 13:01:35.224 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 13:01:35 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 13:01:35.224 EST [545/T1762] DEBUG_API: Processing GET /api/groups in /api/groups
2025-02-25 13:01:35.224 EST [545/T1762] DEBUG_API: SQL: SELECT id,name,enabled,date_added,date_modified,description AS comment FROM "group";
2025-02-25 13:01:35.224 EST [545/T1762] DEBUG_API:      :item = ""
2025-02-25 13:01:35.225 EST [545/T1762] DEBUG_API:      :ids = "(null)"
2025-02-25 13:01:35.225 EST [545/T1762] DEBUG_API: Done
2025-02-25 13:01:35.247 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/info/system ? (null) (Content-Type (null))
2025-02-25 13:01:35.247 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 13:01:35.247 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 13:01:35.247 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 13:01:35 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 13:01:35.248 EST [545/T1762] DEBUG_API: Processing GET /api/info/system in /api/info/system
2025-02-25 13:01:35.248 EST [545/T1762] DEBUG_API: Done
2025-02-25 13:01:35.266 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/info/version ? (null) (Content-Type (null))
2025-02-25 13:01:35.266 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 13:01:35.266 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 13:01:35.267 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 13:01:35 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 13:01:35.267 EST [545/T1762] DEBUG_API: Processing GET /api/info/version in /api/info/version
2025-02-25 13:01:35.267 EST [545/T1762] DEBUG_API: Done
2025-02-25 13:01:35.285 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/info/ftl ? (null) (Content-Type (null))
2025-02-25 13:01:35.286 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 13:01:35.286 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 13:01:35.286 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 13:01:35 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 13:01:35.286 EST [545/T1762] DEBUG_API: Processing GET /api/info/ftl in /api/info/ftl
2025-02-25 13:01:35.287 EST [545/T1762] DEBUG_API: Done
2025-02-25 13:01:35.305 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/dns/blocking ? (null) (Content-Type (null))
2025-02-25 13:01:35.305 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 13:01:35.305 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 13:01:35.306 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 13:01:35 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 13:01:35.306 EST [545/T1762] DEBUG_API: Processing GET /api/dns/blocking in /api/dns/blocking
2025-02-25 13:01:35.306 EST [545/T1762] DEBUG_API: Done
2025-02-25 13:01:35.327 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/info/messages/count ? (null) (Content-Type (null))
2025-02-25 13:01:35.327 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 13:01:35.327 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 13:01:35.327 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 13:01:35 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 13:01:35.327 EST [545/T1762] DEBUG_API: Processing GET /api/info/messages/count in /api/info/messages/count
2025-02-25 13:01:35.329 EST [545/T1762] DEBUG_API: Done

@fongd
Copy link
Author

fongd commented Feb 25, 2025

I should note that logging only began several seconds after clicking the Groups link so whatever is slowing down these requests seems to be occurring before this log snippet began.

@fongd
Copy link
Author

fongd commented Feb 25, 2025

Just for fun and games... please try experimenting with the webserver.threads setting.

By default it is set to be equal to the number of CPU cores minus one. Which is out of an abundance of caution to ensure the entire system doesn't become unrespsonsive.

Could you try increasing it in increments of 5? i.e try 5, if that doesn't make a difference try 10 etc etc etc...

Went up to 20, made no difference.

@DL6ER
Copy link
Member

DL6ER commented Feb 25, 2025

so whatever is slowing down these requests seems to be occurring before this log snippet began.

and there was nothing above that? This seems to suggest your requests did not arrive at Pi-hole's web server at all. Not sure if this is a browser or webserver issue. Could you try again, this time with the Developer Tools of your browser opened (F12) and Network tab selected?

Image

Then check on possibly reported slow requests in detail, e.g.

Image

(in my case, the slowness is expected as I'm not at home right now and connected to my Pi-hole via LTE and a VPN)

@seisfeld
Copy link

I'm trying the other suggestions in minute, but since @DL6ER posted log from the web inspector here is what this looks in Safari. I opened the homepage, then clicked Settings->System. The request is stalled for exactly 30s, then starts processing:

Image Image

@fongd
Copy link
Author

fongd commented Feb 25, 2025

so whatever is slowing down these requests seems to be occurring before this log snippet began.

and there was nothing above that? This seems to suggest your requests did not arrive at Pi-hole's web server at all. Not sure if this is a browser or webserver issue. Could you try again, this time with the Developer Tools of your browser opened (F12) and Network tab selected?

Log entries start getting populated as soon as Safari registers that the page has loaded in the Developer Tools, which makes some sense since I enabled debug.api and API calls only start once the client receives the HTML page. However, Safari spends a lot of time waiting for the initial load (~30 seconds most of the time) which explains why nothing gets logged to FTL.log in the meantime.

@fongd
Copy link
Author

fongd commented Feb 25, 2025

Here is my waterfall to the Groups page, it's similar to @seisfeld's.

Image

As you can see, almost 30 seconds for initial page load then everything is fast after that. My other Pi-hole instance on a dedicated Ubuntu box does not exhibit this behaviour at all but that one is sitting behind a Traefik reverse proxy so it's not completely the same.

@DL6ER
Copy link
Member

DL6ER commented Feb 25, 2025

This is really strange, do you also see it without the traefik proxy, i.e., if directly accessing Pi-hole (over HTTPS or HTTP)?

@fongd
Copy link
Author

fongd commented Feb 25, 2025

No change if I bypass Traefik and directly access Pi-hole on the other (non-problematic) server via http (as opposed to https through Traefik)—everything remains fast and responsive.

On my problematic Pi-hole (which does not sit behind a reverse proxy), it makes no difference whether I use http or https, I get the 30-second delay all the time.

@seisfeld
Copy link

I wonder what's the difference between Safari and Chrome here. I only get this 30 stall in Safari. In Chrome it's snappy. Also it's strange that in Safari double clicking a link makes it load immediately - without the 30 stall.

@fongd
Copy link
Author

fongd commented Feb 25, 2025

The obvious difference I see between Brave (I don't have Chrome) and Safari is that Safari logs possibly CSRF-related errors in its console on page load:

Image

Interestingly, reloading the same page in Safari makes it load relatively instantly (probably similar to double-clicking the link as the workaround that's been suggested) and does not show these errors.

@seisfeld
Copy link

This seems to be core / thread related as @PromoFaux suggested earlier. When I increase the cores in the VM from 2 to 4, the problem in Safari is gone. Another observation, when on 2 cores, while it's stalled, clicking on another link immediately loads that one.

That said, v5 worked fine on VM with 1GB RAM and 2 CPUs.

@DL6ER
Copy link
Member

DL6ER commented Feb 25, 2025

it's strange that in Safari double clicking a link makes it load immediately - without the 30 stall.

Maybe this suggests that - somehow - the first request in Safari doesn't make it to your Pi-hole and by "double clicking" you are actually making two requests in close succession and the second one immediately makes it.

I should note that logging only began several seconds after clicking the Groups link so whatever is slowing down these requests seems to be occurring before this log snippet began.

It's probably worth looking at /var/log/pihole/webserver.log at the same time. When debug.api is true, it should show an access log. This would be helpful in determining if your Pi-hole is seeing this first request at all of if it really needs the almost 30 seconds to travel through the wire... It is also quite suspicious that it is always 30 seconds (or close to). You could also enable debug.webserver = true but this will probably get very verbose in the webserver.log - still, may be helpful to see if anything is happening within these thirty seconds.

@seisfeld
Copy link

seisfeld commented Feb 25, 2025

Just for fun and games... please try experimenting with the webserver.threads setting.

By default it is set to be equal to the number of CPU cores minus one. Which is out of an abundance of caution to ensure the entire system doesn't become unrespsonsive.

Could you try increasing it in increments of 5? i.e try 5, if that doesn't make a difference try 10 etc etc etc...

I went back to two CPUs but changed the webserver.threads from 0 to 2. This also fixes the issue for me in Safari.

@fongd did you restart the FTL service when you changed it? For me it only has an effect when do:

sudo systemctl restart pihole-FTL.service

@fongd
Copy link
Author

fongd commented Feb 25, 2025

I enabled debug.api and debug.webserver. Output gets immediately logged to webserver.log once I send the request from Safari:

[2025-02-25 15:08:08.168 EST 545] DEBUG: Accepted socket 42 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:08.169 EST 545] DEBUG: queued socket 42 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:08.170 EST 545] DEBUG: grabbed socket 42, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:08.170 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:08.170 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:08.193 EST 545] DEBUG: Accepted socket 43 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:08.193 EST 545] DEBUG: queued socket 43 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:08.201 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:08.201 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:08.202 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:08.202 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:08.202 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:08.202 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:08.202 EST 545] DEBUG: REQUEST: GET /api/dns/blocking (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:08.204 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:08.204 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:08.204 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:08 -0500] "GET /api/dns/blocking HTTP/1.1" 200 652 https://pihole.dns02.home.lan/admin/queries Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36
[2025-02-25 15:08:08.204 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:08.204 EST 545] DEBUG: TLS connection 0x7f260343a6d0 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:08.204 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:08.204 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:08.204 EST 545] DEBUG: grabbed socket 43, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:08.205 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:08.205 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:08.228 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:08.228 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:08.228 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:08.228 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:08.234 EST 545] DEBUG: Accepted socket 42 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:08.234 EST 545] DEBUG: queued socket 42 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:18.144 EST 545] DEBUG: Accepted socket 44 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:18.145 EST 545] DEBUG: queued socket 44 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:18.218 EST 545] DEBUG: Accepted socket 46 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:18.219 EST 545] DEBUG: queued socket 46 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:36.223 EST 545] DEBUG: Accepted socket 47 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:36.223 EST 545] DEBUG: queued socket 47 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:37.262 EST 545] DEBUG: http: none, error: No data received (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.263 EST 545] DEBUG: Done processing connection from 192.168.8.10 (29.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.263 EST 545] DEBUG: TLS connection 0x7f260343a250 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.264 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.265 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.265 EST 545] DEBUG: grabbed socket 42, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.266 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.266 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.297 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.297 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.297 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.297 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.299 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.299 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.299 EST 545] DEBUG: REQUEST: GET /admin/groups/clients (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.300 EST 545] DEBUG: Lua environment type 0: 0x7f260369b998, connection 0x7f2603813b00, script /var/www/html/admin/groups-clients.lp (src/webserver/civetweb/mod_lua.inl:2854)
[2025-02-25 15:08:37.307 EST 545] DEBUG: Close Lua environment 0x7f260369b998 (src/webserver/civetweb/mod_lua.inl:3292)
[2025-02-25 15:08:37.308 EST 545] DEBUG: handling GET request to /var/www/html/admin/groups-clients.lp done (template) (src/webserver/civetweb/civetweb.c:15700)
[2025-02-25 15:08:37.308 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.308 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /admin/groups/clients HTTP/1.1" 200 30613 https://pihole.dns02.home.lan/admin/settings/dhcp Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15
[2025-02-25 15:08:37.308 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.308 EST 545] DEBUG: TLS connection 0x7f260343ab50 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.309 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.309 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.309 EST 545] DEBUG: grabbed socket 44, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.309 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.309 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.329 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.329 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.329 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.329 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.329 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.330 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.330 EST 545] DEBUG: REQUEST: GET /api/info/system (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.331 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:37.331 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.331 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /api/info/system HTTP/1.1" 200 964 https://pihole.dns02.home.lan/admin/queries Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36
[2025-02-25 15:08:37.332 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.332 EST 545] DEBUG: TLS connection 0x7f260343a250 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.332 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.332 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.332 EST 545] DEBUG: grabbed socket 46, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.332 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.332 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.350 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.350 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.350 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.350 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.350 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.350 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.350 EST 545] DEBUG: REQUEST: GET /api/dns/blocking (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.352 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:37.352 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.352 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /api/dns/blocking HTTP/1.1" 200 655 https://pihole.dns02.home.lan/admin/queries Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36
[2025-02-25 15:08:37.352 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.352 EST 545] DEBUG: TLS connection 0x7f260343ab50 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.352 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.353 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.353 EST 545] DEBUG: grabbed socket 47, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.353 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.353 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.374 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.374 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.374 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.375 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.375 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.375 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.375 EST 545] DEBUG: REQUEST: GET /api/info/messages/count (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.377 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:37.377 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.377 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /api/info/messages/count HTTP/1.1" 200 630 https://pihole.dns02.home.lan/admin/queries Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36
[2025-02-25 15:08:37.377 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.377 EST 545] DEBUG: TLS connection 0x7f260343a250 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.378 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.378 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.476 EST 545] DEBUG: Accepted socket 42 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:37.476 EST 545] DEBUG: queued socket 42 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:37.477 EST 545] DEBUG: grabbed socket 42, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.477 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.478 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.505 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.505 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.505 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.505 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.505 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.506 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.506 EST 545] DEBUG: REQUEST: GET /api/clients/_suggestions (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.510 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:37.510 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.510 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /api/clients/_suggestions HTTP/1.1" 200 3840 https://pihole.dns02.home.lan/admin/groups/clients Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15
[2025-02-25 15:08:37.510 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.510 EST 545] DEBUG: TLS connection 0x7f260343a250 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.510 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.511 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.512 EST 545] DEBUG: Accepted socket 42 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:37.513 EST 545] DEBUG: queued socket 42 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:37.513 EST 545] DEBUG: Accepted socket 43 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:37.513 EST 545] DEBUG: queued socket 43 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:37.513 EST 545] DEBUG: Accepted socket 44 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:37.513 EST 545] DEBUG: queued socket 44 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:37.513 EST 545] DEBUG: Accepted socket 46 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:37.513 EST 545] DEBUG: queued socket 46 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:37.514 EST 545] DEBUG: Accepted socket 47 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:37.514 EST 545] DEBUG: queued socket 47 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:37.514 EST 545] DEBUG: grabbed socket 42, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.514 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.514 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.518 EST 545] DEBUG: Accepted socket 48 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:37.519 EST 545] DEBUG: queued socket 48 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:37.534 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.534 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.535 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.535 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.535 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.535 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.535 EST 545] DEBUG: REQUEST: GET /api/info/version (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.537 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:37.537 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.537 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /api/info/version HTTP/1.1" 200 1071 https://pihole.dns02.home.lan/admin/groups/clients Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15
[2025-02-25 15:08:37.537 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.537 EST 545] DEBUG: TLS connection 0x7f260343ab50 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.537 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.538 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.538 EST 545] DEBUG: grabbed socket 43, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.538 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.538 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.556 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.556 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.556 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.556 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.556 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.556 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.556 EST 545] DEBUG: REQUEST: GET /api/dns/blocking (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.558 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:37.558 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.558 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /api/dns/blocking HTTP/1.1" 200 653 https://pihole.dns02.home.lan/admin/groups/clients Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15
[2025-02-25 15:08:37.558 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.558 EST 545] DEBUG: TLS connection 0x7f260343a6d0 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.558 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.559 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.559 EST 545] DEBUG: grabbed socket 44, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.559 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.559 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.578 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.578 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.578 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.578 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.578 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.578 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.578 EST 545] DEBUG: REQUEST: GET /api/info/system (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.580 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:37.580 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.580 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /api/info/system HTTP/1.1" 200 965 https://pihole.dns02.home.lan/admin/groups/clients Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15
[2025-02-25 15:08:37.580 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.580 EST 545] DEBUG: TLS connection 0x7f260343a250 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.580 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.581 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.581 EST 545] DEBUG: grabbed socket 46, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.581 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.581 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.599 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.599 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.599 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.599 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.599 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.599 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.600 EST 545] DEBUG: REQUEST: GET /api/groups (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.601 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:37.601 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.602 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /api/groups HTTP/1.1" 200 688 https://pihole.dns02.home.lan/admin/groups/clients Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15
[2025-02-25 15:08:37.602 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.602 EST 545] DEBUG: TLS connection 0x7f260343ab50 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.602 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.602 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.602 EST 545] DEBUG: grabbed socket 47, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.602 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.602 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.620 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.620 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.621 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.621 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.621 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.621 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.621 EST 545] DEBUG: REQUEST: GET /api/info/ftl (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.623 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:37.623 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.623 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /api/info/ftl HTTP/1.1" 200 1463 https://pihole.dns02.home.lan/admin/groups/clients Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15
[2025-02-25 15:08:37.623 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.623 EST 545] DEBUG: TLS connection 0x7f260343a6d0 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.623 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.623 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.624 EST 545] DEBUG: grabbed socket 48, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.624 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.624 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.629 EST 545] DEBUG: Accepted socket 42 (src/webserver/civetweb/civetweb.c:20526)
[2025-02-25 15:08:37.629 EST 545] DEBUG: queued socket 42 (src/webserver/civetweb/civetweb.c:20210)
[2025-02-25 15:08:37.640 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.640 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.640 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.640 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.641 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.641 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.641 EST 545] DEBUG: REQUEST: GET /api/info/messages/count (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.643 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:37.644 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.644 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /api/info/messages/count HTTP/1.1" 200 628 https://pihole.dns02.home.lan/admin/groups/clients Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15
[2025-02-25 15:08:37.644 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.644 EST 545] DEBUG: TLS connection 0x7f260343ab50 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.644 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.644 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)
[2025-02-25 15:08:37.644 EST 545] DEBUG: grabbed socket 42, going busy (src/webserver/civetweb/civetweb.c:20164)
[2025-02-25 15:08:37.644 EST 545] DEBUG: Incoming SSL connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:20329)
[2025-02-25 15:08:37.645 EST 545] DEBUG: TLS accept processing 0x7f2603814418 (src/webserver/civetweb/mod_mbedtls.inl:184)
[2025-02-25 15:08:37.662 EST 545] DEBUG: TLS handshake rc: 0, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:240)
[2025-02-25 15:08:37.663 EST 545] DEBUG: TLS connection 0x7f2603814418 accepted, state: 27 (src/webserver/civetweb/mod_mbedtls.inl:208)
[2025-02-25 15:08:37.663 EST 545] DEBUG: Start processing connection from 192.168.8.10 (src/webserver/civetweb/civetweb.c:19879)
[2025-02-25 15:08:37.663 EST 545] DEBUG: calling get_request (1 times for this connection) (src/webserver/civetweb/civetweb.c:19885)
[2025-02-25 15:08:37.663 EST 545] DEBUG: HTTPS Host: pihole.dns02.home.lan (src/webserver/civetweb/civetweb.c:14580)
[2025-02-25 15:08:37.663 EST 545] DEBUG: http: 1.1, error: none (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.663 EST 545] DEBUG: REQUEST: GET /api/clients (src/webserver/civetweb/civetweb.c:15151)
[2025-02-25 15:08:37.665 EST 545] DEBUG: websocket handling done (src/webserver/civetweb/civetweb.c:15470)
[2025-02-25 15:08:37.665 EST 545] DEBUG: handle_request done (src/webserver/civetweb/civetweb.c:6845)
[2025-02-25 15:08:37.665 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:37 -0500] "GET /api/clients?_=1740514117278 HTTP/1.1" 200 565 https://pihole.dns02.home.lan/admin/groups/clients Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/18.3 Safari/605.1.15
[2025-02-25 15:08:37.665 EST 545] DEBUG: Done processing connection from 192.168.8.10 (0.000000 sec) (src/webserver/civetweb/civetweb.c:20041)
[2025-02-25 15:08:37.666 EST 545] DEBUG: TLS connection 0x7f260343a250 closed (src/webserver/civetweb/mod_mbedtls.inl:221)
[2025-02-25 15:08:37.666 EST 545] DEBUG: Connection closed (src/webserver/civetweb/civetweb.c:20438)
[2025-02-25 15:08:37.666 EST 545] DEBUG: going idle (src/webserver/civetweb/civetweb.c:20144)

Here are the corresponding entries from FTL.log:

2025-02-25 15:08:08.202 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/dns/blocking ? (null) (Content-Type (null))
2025-02-25 15:08:08.202 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:08.203 EST [545/T1762] DEBUG_API: Read sid="bifNUQyGLlI0dXcvGbS9SQ=" from cookie
2025-02-25 15:08:08.203 EST [545/T1762] DEBUG_API: Recognized known user: user_id 2, valid_until: 2025-02-26 15:08:08 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:08.203 EST [545/T1762] DEBUG_API: Processing GET /api/dns/blocking in /api/dns/blocking
2025-02-25 15:08:08.203 EST [545/T1762] DEBUG_API: Done
2025-02-25 15:08:37.300 EST [545/T1762] INFO: Local URI: "/admin/groups/clients"
2025-02-25 15:08:37.300 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 15:08:37.300 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.300 EST [545/T1762] DEBUG_API: Rewriting filename: /var/www/html/admin/groups/clients
2025-02-25 15:08:37.300 EST [545/T1762] DEBUG_API: Rewriting Lua page (settings page): /var/www/html/admin/groups/clients ==> /var/www/html/admin/groups-clients.lp
2025-02-25 15:08:37.330 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/info/system ? (null) (Content-Type (null))
2025-02-25 15:08:37.330 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:37.330 EST [545/T1762] DEBUG_API: Read sid="bifNUQyGLlI0dXcvGbS9SQ=" from cookie
2025-02-25 15:08:37.330 EST [545/T1762] DEBUG_API: Recognized known user: user_id 2, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.330 EST [545/T1762] DEBUG_API: Processing GET /api/info/system in /api/info/system
2025-02-25 15:08:37.331 EST [545/T1762] DEBUG_API: Done
2025-02-25 15:08:37.351 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/dns/blocking ? (null) (Content-Type (null))
2025-02-25 15:08:37.351 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:37.351 EST [545/T1762] DEBUG_API: Read sid="bifNUQyGLlI0dXcvGbS9SQ=" from cookie
2025-02-25 15:08:37.351 EST [545/T1762] DEBUG_API: Recognized known user: user_id 2, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.351 EST [545/T1762] DEBUG_API: Processing GET /api/dns/blocking in /api/dns/blocking
2025-02-25 15:08:37.352 EST [545/T1762] DEBUG_API: Done
2025-02-25 15:08:37.375 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/info/messages/count ? (null) (Content-Type (null))
2025-02-25 15:08:37.375 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:37.375 EST [545/T1762] DEBUG_API: Read sid="bifNUQyGLlI0dXcvGbS9SQ=" from cookie
2025-02-25 15:08:37.376 EST [545/T1762] DEBUG_API: Recognized known user: user_id 2, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.376 EST [545/T1762] DEBUG_API: Processing GET /api/info/messages/count in /api/info/messages/count
2025-02-25 15:08:37.377 EST [545/T1762] DEBUG_API: Done
2025-02-25 15:08:37.506 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/clients/_suggestions ? (null) (Content-Type (null))
2025-02-25 15:08:37.506 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:37.506 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 15:08:37.506 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.507 EST [545/T1762] DEBUG_API: Processing GET /api/clients/_suggestions in /api/clients/_suggestions
2025-02-25 15:08:37.509 EST [545/T1762] DEBUG_API: Done
2025-02-25 15:08:37.535 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/info/version ? (null) (Content-Type (null))
2025-02-25 15:08:37.536 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:37.536 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 15:08:37.536 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.536 EST [545/T1762] DEBUG_API: Processing GET /api/info/version in /api/info/version
2025-02-25 15:08:37.537 EST [545/T1762] DEBUG_API: Done
2025-02-25 15:08:37.557 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/dns/blocking ? (null) (Content-Type (null))
2025-02-25 15:08:37.557 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:37.557 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 15:08:37.557 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.557 EST [545/T1762] DEBUG_API: Processing GET /api/dns/blocking in /api/dns/blocking
2025-02-25 15:08:37.558 EST [545/T1762] DEBUG_API: Done
2025-02-25 15:08:37.579 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/info/system ? (null) (Content-Type (null))
2025-02-25 15:08:37.579 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:37.579 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 15:08:37.579 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.579 EST [545/T1762] DEBUG_API: Processing GET /api/info/system in /api/info/system
2025-02-25 15:08:37.580 EST [545/T1762] DEBUG_API: Done
2025-02-25 15:08:37.600 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/groups ? (null) (Content-Type (null))
2025-02-25 15:08:37.600 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:37.600 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 15:08:37.600 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.600 EST [545/T1762] DEBUG_API: Processing GET /api/groups in /api/groups
2025-02-25 15:08:37.600 EST [545/T1762] DEBUG_API: SQL: SELECT id,name,enabled,date_added,date_modified,description AS comment FROM "group";
2025-02-25 15:08:37.601 EST [545/T1762] DEBUG_API:      :item = ""
2025-02-25 15:08:37.601 EST [545/T1762] DEBUG_API:      :ids = "(null)"
2025-02-25 15:08:37.601 EST [545/T1762] DEBUG_API: Done
2025-02-25 15:08:37.621 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/info/ftl ? (null) (Content-Type (null))
2025-02-25 15:08:37.621 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:37.622 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 15:08:37.622 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.622 EST [545/T1762] DEBUG_API: Processing GET /api/info/ftl in /api/info/ftl
2025-02-25 15:08:37.622 EST [545/T1762] DEBUG_API: Done
2025-02-25 15:08:37.642 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/info/messages/count ? (null) (Content-Type (null))
2025-02-25 15:08:37.642 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:37.642 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 15:08:37.642 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.642 EST [545/T1762] DEBUG_API: Processing GET /api/info/messages/count in /api/info/messages/count
2025-02-25 15:08:37.643 EST [545/T1762] DEBUG_API: Done
2025-02-25 15:08:37.664 EST [545/T1762] DEBUG_API: Requested API URI: 192.168.8.10 -> GET /api/clients ? _=1740514117278 (Content-Type (null))
2025-02-25 15:08:37.664 EST [545/T1762] DEBUG_API: Received no payload
2025-02-25 15:08:37.664 EST [545/T1762] DEBUG_API: Read sid="mMR1kHoi/cLNIe9gGMZ8KQ=" from cookie
2025-02-25 15:08:37.664 EST [545/T1762] DEBUG_API: Recognized known user: user_id 1, valid_until: 2025-02-26 15:08:37 EST, remote_addr 192.168.8.10 (192.168.8.10 at login)
2025-02-25 15:08:37.664 EST [545/T1762] DEBUG_API: Processing GET /api/clients in /api/clients
2025-02-25 15:08:37.664 EST [545/T1762] DEBUG_API: SQL: SELECT id,ip AS client,date_added,date_modified,comment,(SELECT GROUP_CONCAT(group_id) FROM client_by_group g WHERE g.client_id = c.id) AS group_ids FROM client c;
2025-02-25 15:08:37.664 EST [545/T1762] DEBUG_API:      :item = ""
2025-02-25 15:08:37.664 EST [545/T1762] DEBUG_API:      :ids = "(null)"
2025-02-25 15:08:37.665 EST [545/T1762] DEBUG_API: Done

Looking at webserver.log, there seem to be 3 requests being made for this one page request.

I should note that I sometimes still see the dnsmasq signals when I enable debug.all, not sure if or how those may be related.

@fongd
Copy link
Author

fongd commented Feb 25, 2025

Just for fun and games... please try experimenting with the webserver.threads setting.
By default it is set to be equal to the number of CPU cores minus one. Which is out of an abundance of caution to ensure the entire system doesn't become unrespsonsive.
Could you try increasing it in increments of 5? i.e try 5, if that doesn't make a difference try 10 etc etc etc...

I went back to two CPUs but changed the webserver.threads from 0 to 2. This also fixes the issue for me in Safari.

@fongd did you restart the FTL service when you changed it? For me it only has an effect when do:

sudo systemctl restart pihole-FTL.service

Good catch! I did not manually restart pihole-FTL, I figured Pi-hole was already doing that for me by "applying" the changes.

I'm happy to report that in my case, bumping the number of webserver.threads to 2 seems to have fixed my issue.

@seisfeld
Copy link

seisfeld commented Feb 25, 2025

So conclusion is, when setting is 0, threads are (as per @PromoFaux cores -1) with 2 cores only 1 thread. Apparently the system does not like it. When increasing the cores (4 in my case) the default setting would default 2 threads. -> works. When changing manually to 2 it also works with only two cores. So seems it needs at least 2 threads?

Pretty much an edge case we found here haha. Crazy.

But why the heck this does not happen using Chrome...

@fongd
Copy link
Author

fongd commented Feb 25, 2025

Curious why it only affects Safari, though. I wonder if it's because other browsers can keep going even if they consider the page to still be loading?

@pralor-bot
Copy link

This issue has been mentioned on Pi-hole Userspace. There might be relevant details there:

https://discourse.pi-hole.net/t/error-on-v6-if-live-update-is-enabled/75879/21

@pralor-bot
Copy link

This issue has been mentioned on Pi-hole Userspace. There might be relevant details there:

https://discourse.pi-hole.net/t/pihole-6-very-slow/76022/17

@Kryminal
Copy link

Can confirm that changing the number of cpu cores from 2 to 4 seems to have resolved the slow ui issue with Safari on my setup. I did not try yet changing it back to 2 and adjusting the webserver.threads setting

@pralor-bot
Copy link

This issue has been mentioned on Pi-hole Userspace. There might be relevant details there:

https://discourse.pi-hole.net/t/pihole-v6-on-safari-xmlhttprequests-failing-and-therefore-slowing-down-gui/76960/8

@DL6ER
Copy link
Member

DL6ER commented Feb 26, 2025

We are currently discussing increasing the number of threads. Thank your for your input.

Safari is definitely doing something fishy here and we can see the 29 seconds delay here:

[2025-02-25 15:08:08.204 EST 545] ACCESS: 192.168.8.10 - - [25/Feb/2025:15:08:08 -0500] "GET /api/dns/blocking HTTP/1.1" 200 652 https://pihole.dns02.home.lan/admin/queries Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36
[...]
[2025-02-25 15:08:37.262 EST 545] DEBUG: http: none, error: No data received (src/webserver/civetweb/civetweb.c:19969)
[2025-02-25 15:08:37.263 EST 545] DEBUG: Done processing connection from 192.168.8.10 (29.000000 sec) (src/webserver/civetweb/civetweb.c:20041)

From this data alone, it seems Safari is somehow opening (and keeping open!) a connection which it never uses with a timeout of 29 seconds. With only one thread, this is blocking the webserver. With more threads, the webserver will be able to serve the content just fine. This is really an issue when we have only one thread...

@logikkreise
Copy link

logikkreise commented Feb 26, 2025

Can confirm that raising from 1 to 4 cores, editing the webserver.threads value from 0 to 2 and restarting the vm with only 2 cores (running on proxmox) is solving the timeouts as mentioned from @seisfeld.

@jameskimmel
Copy link

jameskimmel commented Feb 27, 2025

But why the heck this does not happen using Chrome...

For me, it also happens in Firefox. It is a little bit harder to trigger, but still possible.
Also it is pretty strange to reproduce. And it also randomly loads instantly sometimes. I first wrongfully saw a correlation between enabling the advanced options.

But I can confirm that raising threads from 0 to 64 (on a 2 core vCPU, Proxmox) solved the issue for me.

Thank you @PromoFaux for that hint on reddit!
If you need further testing changing the number of vCPU cores or threads, just let me know.

@yebo29
Copy link

yebo29 commented Feb 27, 2025

This was also happening to me using FF. Just increased threads and restarted FTL. Testing.
Holy crap that's one heck of an improvement. Can confirm this also seems to fix the issue.

Sign up for free to join this conversation on GitHub. Already have an account? Sign in to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests