Bug#925146: Acknowledgement (grub-probe appears to be stuck (in chroot))
On Wed, Mar 20, 2019 at 05:11:15PM +0100, Harald Dunkel wrote: > Attached you can find the output of > > strace -f -o /tmp/strace.log.txt -ttt -T vgs --options vg_uuid,pv_name > --noheadings --separator : > > run in the chroot. Apparently thats the part that gets extremely > slow. While I agree that that's slow, I'd prefer it if you gave me the thing I asked for if at all possible, since you were also complaining about lack of caching. I can always select out the bit that I need to look at, but it's harder if I don't have the information to start with. After all, if it's just a matter of vgs being slow, then this isn't a GRUB bug at all, no? -- Colin Watson [cjwat...@debian.org]
Bug#925146: Acknowledgement (grub-probe appears to be stuck (in chroot))
On Wed, Mar 20, 2019 at 02:13:22PM +0100, Harald Dunkel wrote:
> PS: strace reveals:
>
> :
> access("/sys/devices/pci:00/:00:1d.2/:40:00.0/nvme/nvme0/nvme0n1/uevent",
> F_OK) = 0
> readlinkat(AT_FDCWD,
> "/sys/devices/pci:00/:00:1d.2/:40:00.0/nvme/nvme0/nvme0n1/subsystem",
> "../../../../../../../class/block", 4096) = 32
> openat(AT_FDCWD,
> "/sys/devices/pci:00/:00:1d.2/:40:00.0/nvme/nvme0/nvme0n1/uevent",
> O_RDONLY|O_CLOEXEC) = 3
> fstat(3, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
> fstat(3, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
> read(3, "MAJOR=259\nMINOR=0\nDEVNAME=nvme0n"..., 4096) = 47
> read(3, "", 4096) = 0
> close(3)= 0
> openat(AT_FDCWD, "/run/udev/data/b259:0", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No
> such file or directory)
> :
This is not very informative: it's a tiny snapshot, and in any case
contains no timing data.
Would it be possible to get the full grub-probe.trace file resulting
from running this in the chroot?
strace -f -tt -s 1024 -o grub-probe.trace /usr/sbin/grub-probe --device
/dev/mapper/vg00-buster --target=fs_uuid
--
Colin Watson [cjwat...@debian.org]
Bug#925146: Acknowledgement (grub-probe appears to be stuck (in chroot))
PPS: There is a lot of screen output of the upgrade session: : : Setting up mdadm (4.1-1) ... update-initramfs: deferring update (trigger activated) Generating grub configuration file ... File descriptor 3 (pipe:[145329]) leaked on vgs invocation. Parent PID 31909: /usr/sbin/grub-probe File descriptor 12 (/dev/pts/2) leaked on vgs invocation. Parent PID 31909: /usr/sbin/grub-probe WARNING: Device /dev/nvme0n1 not initialized in udev database even after waiting 1000 microseconds. /dev/sda: open failed: No medium found WARNING: Device /dev/mapper/pv00 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1p1 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/root not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1p2 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/swap not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/buster not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/export not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/mapper/pv00 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1p1 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/root not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1p2 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/swap not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/buster not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/export not initialized in udev database even after waiting 1000 microseconds. File descriptor 3 (pipe:[145329]) leaked on vgs invocation. Parent PID 31909: /usr/sbin/grub-probe File descriptor 12 (/dev/pts/2) leaked on vgs invocation. Parent PID 31909: /usr/sbin/grub-probe WARNING: Device /dev/nvme0n1 not initialized in udev database even after waiting 1000 microseconds. /dev/sda: open failed: No medium found WARNING: Device /dev/mapper/pv00 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1p1 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/root not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1p2 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/swap not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/buster not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/export not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/mapper/pv00 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1p1 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/root not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1p2 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/swap not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/buster not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/export not initialized in udev database even after waiting 1000 microseconds. File descriptor 3 (pipe:[145329]) leaked on vgs invocation. Parent PID 32160: /usr/sbin/grub-probe File descriptor 12 (/dev/pts/2) leaked on vgs invocation. Parent PID 32160: /usr/sbin/grub-probe WARNING: Device /dev/nvme0n1 not initialized in udev database even after waiting 1000 microseconds. /dev/sda: open failed: No medium found WARNING: Device /dev/mapper/pv00 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1p1 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/root not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/nvme0n1p2 not initialized in udev database even after waiting 1000 microseconds. WARNING: Device /dev/vg00/swap not
Bug#925146: Acknowledgement (grub-probe appears to be stuck (in chroot))
PS: strace reveals:
:
access("/sys/devices/pci:00/:00:1d.2/:40:00.0/nvme/nvme0/nvme0n1/uevent",
F_OK) = 0
readlinkat(AT_FDCWD,
"/sys/devices/pci:00/:00:1d.2/:40:00.0/nvme/nvme0/nvme0n1/subsystem",
"../../../../../../../class/block", 4096) = 32
openat(AT_FDCWD,
"/sys/devices/pci:00/:00:1d.2/:40:00.0/nvme/nvme0/nvme0n1/uevent",
O_RDONLY|O_CLOEXEC) = 3
fstat(3, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
fstat(3, {st_mode=S_IFREG|0644, st_size=4096, ...}) = 0
read(3, "MAJOR=259\nMINOR=0\nDEVNAME=nvme0n"..., 4096) = 47
read(3, "", 4096) = 0
close(3)= 0
openat(AT_FDCWD, "/run/udev/data/b259:0", O_RDONLY|O_CLOEXEC) = -1 ENOENT (No
such file or directory)
:
/mnt/usr/sbin/policy-rc.d is set, of course:
# cat /mnt/usr/sbin/policy-rc.d
#!/bin/sh
exit 101
