found 2.13
thanks

strace -c output:
% time     seconds  usecs/call     calls    errors syscall
------ ----------- ----------- --------- --------- ----------------
 99.64   11.034047           1   6867445   4194635 newfstatat
  0.33    0.036633          18      1996           read
  0.03    0.003487           1      1996           lseek
------ ----------- ----------- --------- --------- ----------------
100.00   11.074167           1   6871437   4194635 total

strace -c -p 28378  10.34s user 43.25s system 45% cpu 1:56.67 total

Snippet from regular strace output:

[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_source_Sources",
 {st_mode=S_IFREG|0644, st_size=5270421, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_source_Sources",
 {st_mode=S_IFREG|0644, st_size=33050, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_non-free_source_Sources",
 {st_mode=S_IFREG|0644, st_size=3342, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_non-free-firmware_source_Sources",
 {st_mode=S_IFREG|0644, st_size=18911, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-amd64_Packages",
 {st_mode=S_IFREG|0644, st_size=2463724, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-amd64_Packages",
 {st_mode=S_IFREG|0644, st_size=2463724, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-i386_Packages",
 {st_mode=S_IFREG|0644, st_size=2066548, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-i386_Packages",
 {st_mode=S_IFREG|0644, st_size=2066548, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-all_Packages",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-all_Packages.xz",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-all_Packages.bz2",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-all_Packages.lzma",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-all_Packages.gz",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-all_Packages.lz4",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-all_Packages.zst",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_binary-all_Packages.uncompressed",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_i18n_Translation-en",
 {st_mode=S_IFREG|0644, st_size=1668510, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_main_i18n_Translation-en",
 {st_mode=S_IFREG|0644, st_size=1668510, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-amd64_Packages",
 {st_mode=S_IFREG|0644, st_size=35708, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-amd64_Packages",
 {st_mode=S_IFREG|0644, st_size=35708, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-i386_Packages",
 {st_mode=S_IFREG|0644, st_size=26846, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-i386_Packages",
 {st_mode=S_IFREG|0644, st_size=26846, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-all_Packages",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-all_Packages.xz",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-all_Packages.bz2",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-all_Packages.lzma",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-all_Packages.gz",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-all_Packages.lz4",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-all_Packages.zst",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_binary-all_Packages.uncompressed",
 0x7ffeddb5eec0, 0) = -1 ENOENT (No such file or directory)
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_i18n_Translation-en",
 {st_mode=S_IFREG|0644, st_size=26398, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_contrib_i18n_Translation-en",
 {st_mode=S_IFREG|0644, st_size=26398, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_non-free_binary-amd64_Packages",
 {st_mode=S_IFREG|0644, st_size=44514, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_non-free_binary-amd64_Packages",
 {st_mode=S_IFREG|0644, st_size=44514, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_non-free_binary-i386_Packages",
 {st_mode=S_IFREG|0644, st_size=27274, ...}, 0) = 0
[pid 28378] newfstatat(AT_FDCWD</var/backups>, 
"/var/lib/apt/lists/http.debian.net_debian_dists_stable-backports_non-free_binary-i386_Packages",
 {st_mode=S_IFREG|0644, st_size=27274, ...}, 0) = 0

Filtering out newfstatat() calls, we see

[pid 28444] ppoll([{fd=9<{eventfd-count=0, eventfd-id=239, 
eventfd-semaphore=0}>, events=POLLIN}], 1, NULL, NULL, 8 <unfinished ...>
[pid 28443] ppoll([{fd=6<{eventfd-count=0, eventfd-id=229, 
eventfd-semaphore=0}>, events=POLLIN}], 1, NULL, NULL, 8 <unfinished ...>
[pid 28442] futex(0x6da8f90, FUTEX_WAIT_PRIVATE, 0, NULL <unfinished ...>
[pid 28378] pselect6(145, [144<pipe:[8903427]>], NULL, NULL, {tv_sec=0, 
tv_nsec=3768247}, NULL) = 0 (Timeout)
[pid 28378] read(144<pipe:[8903427]>, 0xb80b200, 8192) = -1 EAGAIN (Resource 
temporarily unavailable)
[pid 28378] wait4(43984, 0x7ffeddb5f67c, WNOHANG, NULL) = 0
[pid 28378] pselect6(145, [144<pipe:[8903427]>], NULL, NULL, {tv_sec=0, 
tv_nsec=100000000}, NULL) = 0 (Timeout)
[pid 28378] read(144<pipe:[8903427]>, 0xb80b200, 8192) = -1 EAGAIN (Resource 
temporarily unavailable)
[pid 28378] wait4(43984, 0x7ffeddb5f67c, WNOHANG, NULL) = 0
[pid 28378] pselect6(145, [144<pipe:[8903427]>], NULL, NULL, {tv_sec=0, 
tv_nsec=100000000}, NULL) = 0 (Timeout)
[pid 28378] read(144<pipe:[8903427]>, 0xb80b200, 8192) = -1 EAGAIN (Resource 
temporarily unavailable)
[pid 28378] wait4(43984, 0x7ffeddb5f67c, WNOHANG, NULL) = 0
[pid 28378] pselect6(145, [144<pipe:[8903427]>], NULL, NULL, {tv_sec=0, 
tv_nsec=100000000}, NULL) = 0 (Timeout)
[pid 28378] read(144<pipe:[8903427]>, 0xb80b200, 8192) = -1 EAGAIN (Resource 
temporarily unavailable)
[pid 28378] wait4(43984, 0x7ffeddb5f67c, WNOHANG, NULL) = 0
[pid 28378] pselect6(145, [144<pipe:[8903427]>], NULL, NULL, {tv_sec=0, 
tv_nsec=100000000}, NULL) = 0 (Timeout)

Process info:

PID    PPID   USER  NI  RES     USS     SWAP  SHR     S  nTH  P   %CPU   %CUC   
%MEM  TIME+      WCHAN  COMMAND
28378  28342  root  0   328972  310920  0     211780  R  4    22  100.1  8.426  
0.5   237:15.05  -      /usr/bin/python3 /usr/bin/unattended-upgrade

The process tree looks like this (at 10:53 on 24 August):

root     28338  0.0  0.0   2692  1468 ?        Ss   Aug22   0:00  \_ /bin/sh 
/usr/lib/apt/apt.systemd.daily install
root     28342  0.0  0.0   2692  1612 ?        S    Aug22   0:00  |   \_ 
/bin/sh /usr/lib/apt/apt.systemd.daily lock_is_held install
root     28378  8.4  0.5 524748 345904 ?       Sl   Aug22 239:12  |       \_ 
/usr/bin/python3 /usr/bin/unattended-upgrade
root     43984  1.2  0.5 548916 344920 ?       S    10:51   0:01  |           
\_ /usr/bin/python3 /usr/bin/unattended-upgrade
root     44048  0.0  0.0   2692  1460 ?        S    10:51   0:00  |             
  \_ /bin/sh -c /usr/bin/apt-listchanges --apt || test $? -lt 10
root     44049  2.7  0.0  71644 44380 ?        S    10:51   0:02  |             
      \_ /usr/bin/python3 /usr/bin/apt-listchanges --apt
root     46909 46.6  0.3 256656 241956 ?       S    10:52   0:01  |             
          \_ apt-get -qq changelog openjdk-17-jdk-headless=17.0.20.1+1-1
root     46922  0.0  0.0   5564  1452 ?        D    10:53   0:00  |             
              \_ cat /tmp/apt-changelog-h6eEF2/openjdk-17.changelog

So it's not so much that unattended-upgrades as a whole is "stuck"; its
child 43984 is actually doing what it should. The problem is that the parent
process 28378 is in a busy loop for some reason, burning CPU cycles.

AndrĂ¡s

-- 
           If at first you don't succeed, skydiving is not for you.

Reply via email to