squid crash basic auth used delay_pools

Thanks for reporting a bug and helping make Ubuntu better.

I tried to reproduce the problem here, but I think I'm missing something. Maybe you could help me figure it out.

First, I set up squid inside a Jammy container and added the following lines to /etc/squid/squid.conf:

auth_param basic program /usr/lib/squid/basic_ncsa_auth /etc/squid/private/USERS.internet_plain
acl ncsa_users proxy_auth REQUIRED
http_access allow ncsa_users
http_access deny all
acl users_unlim proxy_auth test
delay_pools 1
delay_class 1 1
delay_parameters 1 1024/1024
delay_access 1 allow users_unlim
delay_access 1 deny all

Then, I created the file /etc/squid/private/USERS.internet_plain and added the "test" user to it:

# htpasswd -b /etc/squid/private/USERS.internet_plain test test

I restarted the service, then created another container and tried to use curl with the proxy. Here's what I'm getting:

# curl -vvvv -x test:test@10.96.142.20:3128 example.com
* Trying 10.96.142.20:3128...
* Connected to (nil) (10.96.142.20) port 3128 (#0)
* Proxy auth using Basic with user 'test'
> GET http://example.com/ HTTP/1.1
> Host: example.com
> Proxy-Authorization: Basic dGVzdDp0ZXN0
> User-Agent: curl/7.81.0
> Accept: */*
> Proxy-Connection: Keep-Alive
>
* Empty reply from server
* Closing connection 0
curl: (52) Empty reply from server

When I look at the squid logs, I see that apparently the basic_ncsa_auth script wasn't invoked:

2023/05/09 15:44:41 kid1| Set Current Directory to /var/spool/squid
2023/05/09 15:44:41 kid1| Starting Squid Cache version 5.2 for x86_64-pc-linux-gnu...
2023/05/09 15:44:41 kid1| Service Name: squid
2023/05/09 15:44:41 kid1| Process ID 2515
2023/05/09 15:44:41 kid1| Process Roles: worker
2023/05/09 15:44:41 kid1| With 1024 file descriptors available
2023/05/09 15:44:41 kid1| Initializing IP Cache...
2023/05/09 15:44:41 kid1| DNS Socket created at [::], FD 8
2023/05/09 15:44:41 kid1| DNS Socket created at 0.0.0.0, FD 9
2023/05/09 15:44:41 kid1| Adding nameserver 127.0.0.53 from /etc/resolv.conf
2023/05/09 15:44:41 kid1| Adding domain lxd from /etc/resolv.conf
2023/05/09 15:44:41 kid1| helperOpenServers: Starting 0/20 'basic_ncsa_auth' processes
2023/05/09 15:44:41 kid1| helperOpenServers: No 'basic_ncsa_auth' processes needed.
2023/05/09 15:44:41 kid1| Logfile: opening log daemon:/var/log/squid/access.log
2023/05/09 15:44:41 kid1| Logfile Daemon: opening log /var/log/squid/access.log
2023/05/09 15:44:41 kid1| Local cache digest enabled; rebuild/rewrite every 3600/3600 sec
2023/05/09 15:44:41 kid1| Store logging disabled
2023/05/09 15:44:41 kid1| Swap maxSize 0 + 262144 KB, estimated 20164 objects
2023/05/09 15:44:41 kid1| Target number of buckets: 1008
2023/05/09 15:44:41 kid1| Using 8192 Store buckets
2023/05/09 15:44:41 kid1| Max Mem size: 262144 KB
2023/05/09 15:44:41 kid1| Max Swap size: 0 KB

Could you please take a look at what I did and let me know if there's anything missing?

I will mark this bug as Incomplete in order to make it clear that we're still missing some reproduction steps. Please mark it back to New once you provide the require info. Thanks.

BTW, I forgot to mention that I'm also analysing the stack trace and trying to check if there's anything that could trigger the segfault.

- my network in which squid 192.168.0.0/16 is located
- squid address 192.168.55.35
- luproxy - test user
My minimal squid.conf to reproduce the problem. ACL my_localnet is clearly NOT my network.
--- squid.conf ---
acl my_localnet src 10.1.0.0/16
auth_param basic program /usr/lib/squid/basic_ncsa_auth /etc/squid/private/USERS.internet_plain
acl AuthorizedUsers proxy_auth REQUIRED
http_access allow my_localnet AuthorizedUsers
http_access deny all
acl users_unlim proxy_auth luproxy
# Delay pools
delay_pools 1
delay_class 1 1
delay_parameters 1 1024/1024
delay_access 1 allow users_unlim
delay_access 1 deny all

Eexecuting curl from a neighboring machine will return
$ curl -vvvv -x luproxy:nci8uFuin@192.168.55.35:3128 example.com
* Rebuilt URL to: example.com/
* Trying 192.168.55.35...
* TCP_NODELAY set
* Connected to 192.168.55.35 (192.168.55.35) port 3128 (#0)
* Proxy auth using Basic with user 'luproxy'
> GET http://example.com/ HTTP/1.1
> Host: example.com
> Proxy-Authorization: Basic bHVwcm94eTpuY2k4dUZ1aW4=
> User-Agent: curl/7.58.0
> Accept: */*
> Proxy-Connection: Keep-Alive
>
* Empty reply from server
* Connection #0 to host 192.168.55.35 left intact
curl: (52) Empty reply from server

On the squid machine, we observe a crash
# tail /var/log/apport.log
ERROR: apport (pid 2511534) Sat May 13 20:10:04 2023: called for pid 2511531, signal 11, core limit 0, dump mode 1
ERROR: apport (pid 2511534) Sat May 13 20:10:04 2023: executable: /usr/sbin/squid (command line "(squid-1) --kid squid-1 --foreground -sYC")
ERROR: apport (pid 2511534) Sat May 13 20:10:04 2023: is_closing_session(): no DBUS_SESSION_BUS_ADDRESS in environment
ERROR: apport (pid 2511534) Sat May 13 20:10:04 2023: wrote report /var/crash/_usr_sbin_squid.13.crash

If you specify my network in squid.conf ACL my_localnet or comment out the line "delay_access 1 allow users_unlim", squid executes the request successfully.

#acl my_localnet src 10.1.0.0/16
acl my_localnet src 192.168.0.0/16
auth_param basic program /usr/lib/squid/basic_ncsa_auth /etc/squid/private/USERS.internet_plain
acl AuthorizedUsers proxy_auth REQUIRED
http_access allow my_localnet AuthorizedUsers
http_access deny all
acl users_unlim proxy_auth luproxy
# Delay pools
delay_pools 1
delay_class 1 1
delay_parameters 1 1024/1024
#delay_access 1 allow users_unlim
delay_access 1 deny all

Reproducer:

## From the host
$ lxc launch ubuntu-daily:jammy squid-jj
$ lxc exec squid-jj bash

## From the container
# apt update && apt install -y squid
# apt install apache2-utils --no-install-recommends
# mkdir -p /etc/squid/private
# htpasswd -bpc /etc/squid/private/USERS.internet_plain luproxy nci8uFuin
# cat <<EOF >> /etc/squid/squid.conf
acl my_localnet src all
auth_param basic program /usr/lib/squid/basic_ncsa_auth /etc/squid/private/USERS.internet_plain
acl AuthorizedUsers proxy_auth REQUIRED
http_access allow my_localnet AuthorizedUsers
http_access deny all
acl users_unlim proxy_auth luproxy
# Delay pools
delay_pools 1
delay_class 1 1
delay_parameters 1 1024/1024
delay_access 1 allow users_unlim
delay_access 1 deny all
EOF
# systemctl restart squid

## From the host
$ curl -vvvv -x luproxy:nci8uFuin@CONTAINER_IP_ADDRESS:3128 SOME_URL

This should return an error and you should now be able to see the error in /var/log/apport.log in the container.

Thanks for the reproducer, Athos. I could verify that the problem happens as expected here. I also noticed that my previous attempt at reproducing the failure was successful, but I failed to notice the segfault being reported by apport.

I did a cursory investigation but couldn't find anything strange. I filed an upstream bug and linked it here; meanwhile, I'll see if I can look deeper into the issue.

After discussing this bug briefly during our last housekeeping call, I decided to remove the server-todo tag from it in order to reflect the fact that we're pretty much waiting on upstream to fix the problem. When there's a movement in the upstream bug, I will update this one accordingly.