[Comm] I: rpm in p8 had problems reading *.rpm files

Ivan Zakharyaschev imz на altlinux.org
Чт Июл 11 22:23:46 MSK 2019


On Thu, 11 Jul 2019, Ivan Zakharyaschev wrote:

> Из отдела тестирования сообщили, что в Workstation 8.2 i586 наблюдаются 
> проблемы при чтении пакетов из репозитория p8, смонтированного по NFS.
> 
> # /usr/lib/rpm/rpmq -q -i --list --verbose --package -q --qf "[* %{CHANGELOGNAME}\n %{CHANGELOGTEXT}\n]" /mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm
> ошибка: ошибка запроса /mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm
>
> С обновлённым rpm-4.0.4-alt101.M80P.5.5 такой проблемы нет.
> 
> Я примерно понял, какой код там выполняется, а какой с обновлённым rpm, 
> когда ошибки нет. (Они идут разным путём.)

Проверил у себя на i586 обе версии rpm -- из Workstation 8.2 и 
обновлённую: rpm-4.0.4-alt101.M80P.1 и rpm-4.0.4-alt101.M80P.5.5.

Разницы по strace в этом месте не видно. Проблема не воспроизводится. Как 
можно объяснить, я тоже не знаю. (То, что я написал, тоже не так -- исходя 
из вывода strace.) Странно, что у них она воспроизводится стабильно.
 
> Когда ошибка случается, вывод strace -fy -s1000 перед этим такой:
> 
> open("/mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm", 
> O_RDONLY) = 
> 3</mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>
> fcntl64(3</mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
> F_SETFD, FD_CLOEXEC) = 0
> gettimeofday({tv_sec=1562834023, tv_usec=224425}, NULL) = 0
> fstat64(3</mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
> {st_mode=S_IFREG|0644, st_size=122129, ...}) = 0
> _newselect(4, 
> [3</mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
> NULL, NULL, {tv_sec=1, tv_usec=0}) = 1 (in [3], left {tv_sec=0, 
> tv_usec=999999})
> gettimeofday({tv_sec=1562834023, tv_usec=224634}, NULL) = 0
> read(3</mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
> "\355\253\356\333\3\0\0\0\0\1sssd-ad-1.16.4-alt1.M80P.1\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\1\0\5\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0\0", 
> 96) = 96
> gettimeofday({tv_sec=1562834023, tv_usec=224766}, NULL) = 0
> _newselect(4, 
> [3</mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
> NULL, NULL, {tv_sec=1, tv_usec=0}) = 1 (in [3], left {tv_sec=0, 
> tv_usec=999999})
> gettimeofday({tv_sec=1562834023, tv_usec=224870}, NULL) = 0
> read(3</mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
> "\216\255\350\1\0\0\0\0\0\0\0\5\0\0\0\230", 16) = 16
> gettimeofday({tv_sec=1562834023, tv_usec=224953}, NULL) = 0
> _newselect(4, 
> [3</mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
> NULL, NULL, {tv_sec=1, tv_usec=0}) = 1 (in [3], left {tv_sec=0, 
> tv_usec=999999})
> gettimeofday({tv_sec=1562834023, tv_usec=225069}, NULL) = 0
> read(3</mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
> "\0\0\0>\0\0\0\7\0\0\0\210\0\0\0\20\0\0\1\r\0\0\0\6\0\0\0\0\0\0\0\1\0\0\3\350\0\0\0\4\0\0\0,\0\0\0\1\0\0\3\354\0\0\0\7\0\0\0000\0\0\0\20\0\0\3\355\0\0\0\7\0\0\0@\0\0\0H7ce3371ed3cb8e8bcea5a341a029ac364173f7ab\0\0\0\0\0\1\333\271\271v\nq\244\1\271&VY\242\257RH\260\220\210F\4\0\21\2\0\6\5\2]\33\225b\0\n\t\20\334\236\225\302#\21\24\263GF\0\237`,L\375\226t\370s\35\206\202\"\265k\364s:\206\v\31\0\240\256VMa\356w*M\352\36\374!Z^J\277\16\356\256?\0\0\0>\0\0\0\7\377\377\377\260\0\0\0\20", 
> 232) = 232
> gettimeofday({tv_sec=1562834023, tv_usec=225146}, NULL) = 0
> fstat64(3</mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
> {st_mode=S_IFREG|0644, st_size=122129, ...}) = 0
> gettimeofday({tv_sec=1562834023, tv_usec=225226}, NULL) = 0
> close(3</mnt/repo-nhcR/pub/distributions/ALTLinux/p8/branch/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>) 
> = 0
> 
> Когда ошибки нет, аналогичный кусок у меня (правда, на x86_64) такой:

> Обращает на себя внимание наличие при работе старого rpm вызовов 
> gettimeofday(), а в обновлённом -- просто select(). Это свидетельствует, 
> что в обновлённом rpm используется набор функций для работы с файлами 
> "ufdio", а в старом -- "fdio". Наверное, "fdio" глючный. Вероятно, выбор в 

Вот как это выглядит у меня с rpm из workstation 8.2 на i586

3016  open("/ALT/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm", 
O_RDONLY) = 
3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>
3016  
fcntl64(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
F_SETFD, FD_CLOEXEC) = 0
3016  gettimeofday({1562871385, 345814}, NULL) = 0
3016  
fstat64(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
{st_mode=S_IFREG|0644, st_size=122129, ...}) = 0
3016  
fadvise64(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
0, 0, POSIX_FADV_RANDOM) = 0
3016  _newselect(4, 
[3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
NULL, NULL, {1, 0}) = 1 (in [3], left {0, 999998})
3016  gettimeofday({1562871385, 345980}, NULL) = 0
3016  
read(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
"\355\253\356\333\3\0\0\0\0\1sssd-ad-1.16.4-alt1.M8"..., 96) = 96
3016  gettimeofday({1562871385, 346037}, NULL) = 0
3016  _newselect(4, 
[3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
NULL, NULL, {1, 0}) = 1 (in [3], left {0, 999998})
3016  gettimeofday({1562871385, 346102}, NULL) = 0
3016  
read(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
"\216\255\350\1\0\0\0\0\0\0\0\5\0\0\0\230", 16) = 16
3016  gettimeofday({1562871385, 346155}, NULL) = 0
3016  _newselect(4, 
[3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
NULL, NULL, {1, 0}) = 1 (in [3], left {0, 999999})
3016  gettimeofday({1562871385, 346223}, NULL) = 0
3016  
read(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
"\0\0\0>\0\0\0\7\0\0\0\210\0\0\0\20\0\0\1\r\0\0\0\6\0\0\0\0\0\0\0\1"..., 
232) = 232
3016  gettimeofday({1562871385, 346276}, NULL) = 0
3016  
fstat64(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
{st_mode=S_IFREG|0644, st_size=122129, ...}) = 0
3016  open("/usr/share/locale/ru_RU.UTF-8/LC_MESSAGES/rpm.mo", O_RDONLY) = 
-1 ENOENT (No such file or directory)
3016  open("/usr/share/locale/ru_RU.utf8/LC_MESSAGES/rpm.mo", O_RDONLY) = 
-1 ENOENT (No such file or directory)
3016  open("/usr/share/locale/ru_RU/LC_MESSAGES/rpm.mo", O_RDONLY) = -1 
ENOENT (No such file or directory)
3016  open("/usr/share/locale/ru.UTF-8/LC_MESSAGES/rpm.mo", O_RDONLY) = -1 
ENOENT (No such file or directory)
3016  open("/usr/share/locale/ru.utf8/LC_MESSAGES/rpm.mo", O_RDONLY) = -1 
ENOENT (No such file or directory)
3016  open("/usr/share/locale/ru/LC_MESSAGES/rpm.mo", O_RDONLY) = 
4</usr/share/locale/ru/LC_MESSAGES/rpm.mo>
3016  fstat64(4</usr/share/locale/ru/LC_MESSAGES/rpm.mo>, 
{st_mode=S_IFREG|0644, st_size=94761, ...}) = 0
3016  mmap2(NULL, 94761, PROT_READ, MAP_PRIVATE, 
4</usr/share/locale/ru/LC_MESSAGES/rpm.mo>, 0) = 0xb7028000
3016  close(4</usr/share/locale/ru/LC_MESSAGES/rpm.mo>) = 0
3016  _newselect(4, 
[3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
NULL, NULL, {1, 0}) = 1 (in [3], left {0, 999998})
3016  gettimeofday({1562871385, 346750}, NULL) = 0
3016  
read(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
"\216\255\350\1\0\0\0\0\0\0\0004\0\0*`", 16) = 16
3016  gettimeofday({1562871385, 346803}, NULL) = 0
3016  _newselect(4, 
[3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
NULL, NULL, {1, 0}) = 1 (in [3], left {0, 999998})
3016  gettimeofday({1562871385, 346870}, NULL) = 0
3016  
read(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
"\0\0\0?\0\0\0\7\0\0*P\0\0\0\20\0\0\0d\0\0\0\10\0\0\0\0\0\0\0\1"..., 
11680) = 11680
3016  gettimeofday({1562871385, 346930}, NULL) = 0
3016  
fadvise64(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
0, 0, POSIX_FADV_NORMAL) = 0
3016  sysinfo({uptime=2981, loads=[3584, 1920, 320], totalram=2118459392, 
freeram=1635254272, sharedram=4005888, bufferram=32104448, 
totalswap=2117070848, freeswap=2117070848, procs=208, 
totalhigh=1216208896, freehigh=958918656, mem_unit=1}) = 0
3016  gettimeofday({1562871385, 347074}, NULL) = 0
3016  
close(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>) 
= 0

И вот как с обновлённым rpm:

3435  open("/ALT/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm", 
O_RDONLY|O_LARGEFILE) = 
3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>
3435  
fcntl64(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
F_SETFD, FD_CLOEXEC) = 0
3435  gettimeofday({1562871641, 593028}, NULL) = 0
3435  
fstat64(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
{st_mode=S_IFREG|0644, st_size=122129, ...}) = 0
3435  
fadvise64_64(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
0, 0, POSIX_FADV_RANDOM) = 0
3435  _newselect(4, 
[3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
NULL, NULL, {1, 0}) = 1 (in [3], left {0, 999998})
3435  gettimeofday({1562871641, 593193}, NULL) = 0
3435  
read(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
"\355\253\356\333\3\0\0\0\0\1sssd-ad-1.16.4-alt1.M8"..., 96) = 96
3435  gettimeofday({1562871641, 593272}, NULL) = 0
3435  _newselect(4, 
[3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
NULL, NULL, {1, 0}) = 1 (in [3], left {0, 999999})
3435  gettimeofday({1562871641, 593327}, NULL) = 0
3435  
read(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
"\216\255\350\1\0\0\0\0\0\0\0\5\0\0\0\230", 16) = 16
3435  gettimeofday({1562871641, 593369}, NULL) = 0
3435  _newselect(4, 
[3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
NULL, NULL, {1, 0}) = 1 (in [3], left {0, 999999})
3435  gettimeofday({1562871641, 593423}, NULL) = 0
3435  
read(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
"\0\0\0>\0\0\0\7\0\0\0\210\0\0\0\20\0\0\1\r\0\0\0\6\0\0\0\0\0\0\0\1"..., 
232) = 232
3435  gettimeofday({1562871641, 593466}, NULL) = 0
3435  
fstat64(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
{st_mode=S_IFREG|0644, st_size=122129, ...}) = 0
3435  open("/usr/share/locale/ru_RU.UTF-8/LC_MESSAGES/rpm.mo", O_RDONLY) = 
-1 ENOENT (No such file or directory)
3435  open("/usr/share/locale/ru_RU.utf8/LC_MESSAGES/rpm.mo", O_RDONLY) = 
-1 ENOENT (No such file or directory)
3435  open("/usr/share/locale/ru_RU/LC_MESSAGES/rpm.mo", O_RDONLY) = -1 
ENOENT (No such file or directory)
3435  open("/usr/share/locale/ru.UTF-8/LC_MESSAGES/rpm.mo", O_RDONLY) = -1 
ENOENT (No such file or directory)
3435  open("/usr/share/locale/ru.utf8/LC_MESSAGES/rpm.mo", O_RDONLY) = -1 
ENOENT (No such file or directory)
3435  open("/usr/share/locale/ru/LC_MESSAGES/rpm.mo", O_RDONLY) = 
4</usr/share/locale/ru/LC_MESSAGES/rpm.mo>
3435  fstat64(4</usr/share/locale/ru/LC_MESSAGES/rpm.mo>, 
{st_mode=S_IFREG|0644, st_size=94761, ...}) = 0
3435  mmap2(NULL, 94761, PROT_READ, MAP_PRIVATE, 
4</usr/share/locale/ru/LC_MESSAGES/rpm.mo>, 0) = 0xb706a000
3435  close(4</usr/share/locale/ru/LC_MESSAGES/rpm.mo>) = 0
3435  _newselect(4, 
[3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
NULL, NULL, {1, 0}) = 1 (in [3], left {0, 999999})
3435  gettimeofday({1562871641, 593858}, NULL) = 0
3435  
read(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
"\216\255\350\1\0\0\0\0\0\0\0004\0\0*`", 16) = 16
3435  gettimeofday({1562871641, 593900}, NULL) = 0
3435  _newselect(4, 
[3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>], 
NULL, NULL, {1, 0}) = 1 (in [3], left {0, 999999})
3435  gettimeofday({1562871641, 593964}, NULL) = 0
3435  
read(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
"\0\0\0?\0\0\0\7\0\0*P\0\0\0\20\0\0\0d\0\0\0\10\0\0\0\0\0\0\0\1"..., 
11680) = 11680
3435  gettimeofday({1562871641, 594014}, NULL) = 0
3435  
fadvise64_64(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>, 
0, 0, POSIX_FADV_NORMAL) = 0
3435  sysinfo({uptime=3237, loads=[21632, 11008, 3840], 
totalram=2118459392, freeram=1432961024, sharedram=4022272, 
bufferram=32686080, totalswap=2117070848, freeswap=2117070848, procs=208, 
totalhigh=1216208896, freehigh=840957952, mem_unit=1}) = 0
3435  gettimeofday({1562871641, 594145}, NULL) = 0
3435  
close(3</space/mirror/p8/files/i586/RPMS/sssd-ad-1.16.4-alt1.M80P.1.i586.rpm>) 
= 0

Разницы в том, что возвращает read, нет (и существенной разницы в 
результатах прочих вызовов). Во всех трёх случаях. У них, когда ошибка 
случается, времени на последний read уходит побольше, чем у меня. Почему 
это сказаывается на поведении rpm, непонятно.


> пользу "ufdio" поменялся в серии патчей Алексея Турбина at@ про Large File 
> Support.
> 
> Вряд ли пакеты из репозитория как-то плохо сформированы, да этот пример 
> вроде и не очень большого файла пакета.
> 
> Так что могу посоветовать всем пользователям p8 обновить сначала rpm, 
> чтобы избегать таких проблем.
> 
> -- 
> Best regards,
> Ivan


Подробная информация о списке рассылки community