Comment 18 for bug 2004130

Revision history for this message
Simon Déziel (sdeziel) wrote (last edit ):

Verification for Jammy:

# enable -proposed
# run `apt-get update`
root@sdeziel-lemur:~# apt-get update && apt-get dist-upgrade
...
Fetched 935 kB in 1s (867 kB/s)
Reading package lists... Done

# pull the updated package
root@sdeziel-lemur:~# apt-get install ubuntu-advantage-tools
Reading package lists... Done
Building dependency tree... Done
Reading state information... Done
The following packages will be upgraded:
   ubuntu-advantage-tools (27.13.2~22.04.1 => 27.13.3~22.04.1)
1 upgraded, 0 newly installed, 0 to remove and 44 not upgraded.
Need to get 172 kB of archives.
After this operation, 1,024 B of additional disk space will be used.
Get:1 https://apt.sdeziel.info/archive/ubuntu jammy-proposed/main amd64 ubuntu-advantage-tools amd64 27.13.3~22.04.1 [172 kB]
Fetched 172 kB in 1s (256 kB/s)
[master fc03eab] saving uncommitted changes in /etc prior to apt run
 Author: Simon Deziel <email address hidden>
 1 file changed, 1 insertion(+), 1 deletion(-)
Reading changelogs... Done
apt-listchanges: Do you want to continue? [Y/n]
Preconfiguring packages ...
(Reading database ... 135862 files and directories currently installed.)
Preparing to unpack .../ubuntu-advantage-tools_27.13.3~22.04.1_amd64.deb ...
Unpacking ubuntu-advantage-tools (27.13.3~22.04.1) over (27.13.2~22.04.1) ...
Setting up ubuntu-advantage-tools (27.13.3~22.04.1) ...
ua-timer.timer is a disabled or a static unit, not starting it.
Processing triggers for man-db (2.10.2-1) ...

# run `apt-get update` to trigger the esm apt hook
root@sdeziel-lemur:~# apt-get update
...
Fetched 483 kB in 1s (342 kB/s)
Reading package lists... Done

# check esm-cache journal output:

$ journalctl -fu esm-cache
Jan 31 20:16:06 sdeziel-lemur python3[2395775]: File "/usr/lib/ubuntu-advantage/esm_cache.py", line 11, in main
Jan 31 20:16:06 sdeziel-lemur python3[2395775]: update_esm_caches(cfg)
Jan 31 20:16:06 sdeziel-lemur python3[2395775]: File "/usr/lib/python3/dist-packages/uaclient/apt.py", line 636, in update_esm_caches
Jan 31 20:16:06 sdeziel-lemur python3[2395775]: cache.update()
Jan 31 20:16:06 sdeziel-lemur python3[2395775]: File "/usr/lib/python3/dist-packages/apt/cache.py", line 575, in update
Jan 31 20:16:06 sdeziel-lemur python3[2395775]: raise FetchFailedException()
Jan 31 20:16:06 sdeziel-lemur python3[2395775]: apt.cache.FetchFailedException
Jan 31 20:16:06 sdeziel-lemur systemd[1]: esm-cache.service: Main process exited, code=exited, status=1/FAILURE
Jan 31 20:16:06 sdeziel-lemur systemd[1]: esm-cache.service: Failed with result 'exit-code'.
Jan 31 20:16:06 sdeziel-lemur systemd[1]: Failed to start Update the local ESM caches.
Jan 31 20:17:30 sdeziel-lemur systemd[1]: Starting Update the local ESM caches...
Jan 31 20:17:38 sdeziel-lemur python3[2398857]: Failed to fetch the ESM Apt Cache
Jan 31 20:17:38 sdeziel-lemur systemd[1]: esm-cache.service: Deactivated successfully.
Jan 31 20:17:38 sdeziel-lemur systemd[1]: Finished Update the local ESM caches.

# confirm no service failed:

root@sdeziel-lemur:~# systemctl --failed
  UNIT LOAD ACTIVE SUB DESCRIPTION
0 loaded units listed.

# confirm that no *new* crash reports were generated:
# the crash at 20:13 is from the first `apt-get update`. That aligns with the second
# esm-cache.service activation that happened at 20:17:30 (see journal output from above).
root@sdeziel-lemur:~# ll /var/crash/
total 24
drwxrwxrwt 1 root root 92 Jan 29 20:13 ./
drwxr-xr-x 1 root root 122 Sep 6 03:59 ../
-rw-r----- 1 root root 23582 Jan 29 20:13 _usr_lib_ubuntu-advantage_esm_cache.py.0.crash

# confirm in the proxy logs that the connection to https://esm.ubuntu.com was indeed denied:

root@squid:~# tail -f /var/log/squid/access.log
...
1675214254.077 0 2001:470:b1c3:7943::2 TCP_DENIED/403 3839 CONNECT esm.ubuntu.com:443 - HIER_NONE/- text/html
1675214254.084 0 2001:470:b1c3:7943::2 TCP_DENIED/403 3839 CONNECT esm.ubuntu.com:443 - HIER_NONE/- text/html
1675214258.086 0 2001:470:b1c3:7943::2 TCP_DENIED/403 3839 CONNECT esm.ubuntu.com:443 - HIER_NONE/- text/html
1675214258.092 0 2001:470:b1c3:7943::2 TCP_DENIED/403 3839 CONNECT esm.ubuntu.com:443 - HIER_NONE/- text/html

# double check no crash report is generated:
root@sdeziel-lemur:~# rm /var/crash/_usr_lib_ubuntu-advantage_esm_cache.py.0.crash
root@sdeziel-lemur:~# apt-get update
...
Fetched 483 kB in 2s (318 kB/s)
Reading package lists... Done
root@sdeziel-lemur:~# sleep 30
root@sdeziel-lemur:~# ll /var/crash/
total 0
drwxrwxrwt 1 root root 0 Jan 31 20:26 ./
drwxr-xr-x 1 root root 122 Sep 6 03:59 ../