11:52 a.m.
When converting from a single transaction to a linked list, I
forgot to free the storage for each member of the list.
Reported-by: Richard W.M. Jones <rjones(a)redhat.com>
Fixes: 7f5bb9bf13f041ea7702bda557d9dd668bc3423a
Signed-off-by: Eric Blake <eblake(a)redhat.com>
---
I'm still not sure why 'make check' passes while 'make
check-valgrind'
fails for TESTS=test-nbd, but this at least avoids the memory leak.
plugins/nbd/nbd.c | 2 ++
1 file changed, 2 insertions(+)
diff --git a/plugins/nbd/nbd.c b/plugins/nbd/nbd.c
index b844bf5..425abe4 100644
--- a/plugins/nbd/nbd.c
+++ b/plugins/nbd/nbd.c
@@ -334,6 +334,7 @@ nbd_reply_raw (struct handle *h, int *fd)
}
*fd = trans->u.fds[1];
+ free (trans);
switch (be32toh (rep.error)) {
case NBD_SUCCESS:
if (trans->buf && read_full (h->fd, trans->buf, trans->count)
< 0)
@@ -399,6 +400,7 @@ nbd_reader (void *handle)
abort ();
}
close (trans->u.fds[1]);
+ free (trans);
}
return NULL;
}
--
2.14.3
12:21 p.m.
On Sat, Dec 02, 2017 at 11:52:31AM -0600, Eric Blake wrote:
When converting from a single transaction to a linked list, I
forgot to free the storage for each member of the list.
Reported-by: Richard W.M. Jones <rjones(a)redhat.com>
Fixes: 7f5bb9bf13f041ea7702bda557d9dd668bc3423a
Signed-off-by: Eric Blake <eblake(a)redhat.com>
---
I'm still not sure why 'make check' passes while 'make
check-valgrind'
fails for TESTS=test-nbd, but this at least avoids the memory leak.
plugins/nbd/nbd.c | 2 ++
1 file changed, 2 insertions(+)
diff --git a/plugins/nbd/nbd.c b/plugins/nbd/nbd.c
index b844bf5..425abe4 100644
--- a/plugins/nbd/nbd.c
+++ b/plugins/nbd/nbd.c
@@ -334,6 +334,7 @@ nbd_reply_raw (struct handle *h, int *fd)
}
*fd = trans->u.fds[1];
+ free (trans);
switch (be32toh (rep.error)) {
case NBD_SUCCESS:
if (trans->buf && read_full (h->fd, trans->buf, trans->count)
< 0)
@@ -399,6 +400,7 @@ nbd_reader (void *handle)
abort ();
}
close (trans->u.fds[1]);
+ free (trans);
}
return NULL;
}
Can this be right? valgrind seems to be saying that there are
double-free errors when I add this patch (see below).
Rich.
FAIL: test-nbd
==============
/home/rjones/d/nbdkit/src/nbdkit -U /tmp/nbdkitwuqXQ0/sock -P /tmp/nbdkitwuqXQ0/pid -f -v
-o /home/rjones/d/nbdkit/plugins/file/.libs/nbdkit-file-plugin.so file=file-data
==17992== Memcheck, a memory error detector
==17992== Copyright (C) 2002-2017, and GNU GPL'd, by Julian Seward et al.
==17992== Using Valgrind-3.13.0 and LibVEX; rerun with -h for copyright info
==17992== Command: /home/rjones/d/nbdkit/src/nbdkit -U /tmp/nbdkitwuqXQ0/sock -P
/tmp/nbdkitwuqXQ0/pid -f -v -o
/home/rjones/d/nbdkit/plugins/file/.libs/nbdkit-file-plugin.so file=file-data
==17992==
nbdkit: debug: successfully loaded TLS certificates from /home/rjones/.pki/nbdkit
nbdkit: debug: TLS enabled
nbdkit: debug: registering /home/rjones/d/nbdkit/plugins/file/.libs/nbdkit-file-plugin.so
nbdkit: debug: registered /home/rjones/d/nbdkit/plugins/file/.libs/nbdkit-file-plugin.so
(name file)
nbdkit: debug: /home/rjones/d/nbdkit/plugins/file/.libs/nbdkit-file-plugin.so: load
nbdkit: debug: /home/rjones/d/nbdkit/plugins/file/.libs/nbdkit-file-plugin.so: config
key=file, value=file-data
nbdkit: debug: /home/rjones/d/nbdkit/plugins/file/.libs/nbdkit-file-plugin.so:
config_complete
nbdkit: debug: bound to unix socket /tmp/nbdkitwuqXQ0/sock
nbdkit: debug: written pidfile /tmp/nbdkitwuqXQ0/pid
/home/rjones/d/nbdkit/src/nbdkit -U /tmp/nbdkitFCDAKC/sock -P /tmp/nbdkitFCDAKC/pid -f -v
-e wrap /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so
socket=/tmp/nbdkitwuqXQ0/sock
==18076== Memcheck, a memory error detector
==18076== Copyright (C) 2002-2017, and GNU GPL'd, by Julian Seward et al.
==18076== Using Valgrind-3.13.0 and LibVEX; rerun with -h for copyright info
==18076== Command: /home/rjones/d/nbdkit/src/nbdkit -U /tmp/nbdkitFCDAKC/sock -P
/tmp/nbdkitFCDAKC/pid -f -v -e wrap
/home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so
socket=/tmp/nbdkitwuqXQ0/sock
==18076==
nbdkit: debug: successfully loaded TLS certificates from /home/rjones/.pki/nbdkit
nbdkit: debug: TLS enabled
nbdkit: debug: registering /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so
nbdkit: debug: registered /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so
(name nbd)
nbdkit: debug: /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so: load
nbdkit: debug: /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so: config
key=socket, value=/tmp/nbdkitwuqXQ0/sock
nbdkit: debug: /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so:
config_complete
nbdkit: debug: bound to unix socket /tmp/nbdkitFCDAKC/sock
nbdkit: debug: written pidfile /tmp/nbdkitFCDAKC/pid
/home/rjones/d/nbdkit/src/nbdkit -U /tmp/nbdkitnRE48m/sock -P /tmp/nbdkitnRE48m/pid -f -v
-o /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so
socket=/tmp/nbdkitFCDAKC/sock export=wrap
==18105== Memcheck, a memory error detector
==18105== Copyright (C) 2002-2017, and GNU GPL'd, by Julian Seward et al.
==18105== Using Valgrind-3.13.0 and LibVEX; rerun with -h for copyright info
==18105== Command: /home/rjones/d/nbdkit/src/nbdkit -U /tmp/nbdkitnRE48m/sock -P
/tmp/nbdkitnRE48m/pid -f -v -o
/home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so socket=/tmp/nbdkitFCDAKC/sock
export=wrap
==18105==
nbdkit: debug: successfully loaded TLS certificates from /home/rjones/.pki/nbdkit
nbdkit: debug: TLS enabled
nbdkit: debug: registering /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so
nbdkit: debug: registered /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so
(name nbd)
nbdkit: debug: /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so: load
nbdkit: debug: /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so: config
key=socket, value=/tmp/nbdkitFCDAKC/sock
nbdkit: debug: /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so: config
key=export, value=wrap
nbdkit: debug: /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so:
config_complete
nbdkit: debug: bound to unix socket /tmp/nbdkitnRE48m/sock
nbdkit: debug: written pidfile /tmp/nbdkitnRE48m/pid
libguestfs: trace: set_verbose true
libguestfs: trace: set_verbose = 0
libguestfs: trace: set_backend "appliance"
libguestfs: trace: set_backend = 0
libguestfs: create: flags = 0, handle = 0x24282d0, program = test-nbd
libguestfs: trace: add_drive "" "format:raw" "protocol:nbd"
"server:unix:/tmp/nbdkitnRE48m/sock"
libguestfs: trace: add_drive = 0
libguestfs: trace: launch
libguestfs: trace: get_tmpdir
libguestfs: trace: get_tmpdir = "/tmp"
libguestfs: trace: version
libguestfs: trace: version = <struct guestfs_version = major: 1, minor: 36, release: 7,
extra: fedora=26,release=1.fc26,libvirt, >
libguestfs: trace: get_backend
libguestfs: trace: get_backend = "direct"
libguestfs: launch: program=test-nbd
libguestfs: launch: version=1.36.7fedora=26,release=1.fc26,libvirt
libguestfs: launch: backend registered: unix
libguestfs: launch: backend registered: uml
libguestfs: launch: backend registered: libvirt
libguestfs: launch: backend registered: direct
libguestfs: launch: backend=direct
libguestfs: launch: tmpdir=/tmp/libguestfsYp1w4f
libguestfs: launch: umask=0002
libguestfs: launch: euid=1000
libguestfs: trace: get_backend_setting "force_tcg"
libguestfs: trace: get_backend_setting = NULL (error)
libguestfs: trace: get_cachedir
libguestfs: trace: get_cachedir = "/var/tmp"
libguestfs: begin building supermin appliance
libguestfs: run supermin
libguestfs: command: run: /usr/bin/supermin
libguestfs: command: run: \ --build
libguestfs: command: run: \ --verbose
libguestfs: command: run: \ --if-newer
libguestfs: command: run: \ --lock /var/tmp/.guestfs-1000/lock
libguestfs: command: run: \ --copy-kernel
libguestfs: command: run: \ -f ext2
libguestfs: command: run: \ --host-cpu x86_64
libguestfs: command: run: \ /usr/lib64/guestfs/supermin.d
libguestfs: command: run: \ -o /var/tmp/.guestfs-1000/appliance.d
supermin: version: 5.1.19
supermin: rpm: detected RPM version 4.13
supermin: package handler: fedora/rpm
supermin: acquiring lock on /var/tmp/.guestfs-1000/lock
supermin: if-newer: output does not need rebuilding
libguestfs: finished building supermin appliance
libguestfs: begin testing qemu features
libguestfs: trace: get_cachedir
libguestfs: trace: get_cachedir = "/var/tmp"
libguestfs: checking for previously cached test results of /usr/bin/qemu-kvm, in
/var/tmp/.guestfs-1000
libguestfs: loading previously cached test results
libguestfs: qemu version: 2.10
libguestfs: qemu mandatory locking: yes
libguestfs: trace: get_sockdir
libguestfs: trace: get_sockdir = "/run/user/1000"
libguestfs: finished testing qemu features
libguestfs: trace: get_backend_setting "gdb"
libguestfs: trace: get_backend_setting = NULL (error)
[00085ms] /usr/bin/qemu-kvm \
-global virtio-blk-pci.scsi=off \
-nodefconfig \
-enable-fips \
-nodefaults \
-display none \
-machine accel=kvm:tcg \
-cpu host \
-m 500 \
-no-reboot \
-rtc driftfix=slew \
-no-hpet \
-global kvm-pit.lost_tick_policy=discard \
-kernel /var/tmp/.guestfs-1000/appliance.d/kernel \
-initrd /var/tmp/.guestfs-1000/appliance.d/initrd \
-object rng-random,filename=/dev/urandom,id=rng0 \
-device virtio-rng-pci,rng=rng0 \
-device virtio-scsi-pci,id=scsi \
-drive file=nbd:unix:/tmp/nbdkitnRE48m/sock,cache=writeback,format=raw,id=hd0,if=none
\
-device scsi-hd,drive=hd0 \
-drive
file=/var/tmp/.guestfs-1000/appliance.d/root,snapshot=on,id=appliance,cache=unsafe,if=none,format=raw
\
-device scsi-hd,drive=appliance \
-device virtio-serial-pci \
-serial stdio \
-device sga \
-chardev socket,path=/run/user/1000/libguestfsUEHqc9/guestfsd.sock,id=channel0 \
-device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \
-append 'panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000
no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests
tsc=reliable 8250.nr_uarts=1 root=/dev/sdb selinux=0 guestfs_verbose=1
TERM=xterm-256color'
nbdkit: debug: accepted connection
nbdkit: debug: /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so: open
readonly=0
nbdkit: debug: accepted connection
nbdkit: debug: /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so: open
readonly=0
nbdkit: debug: accepted connection
nbdkit: debug: /home/rjones/d/nbdkit/plugins/file/.libs/nbdkit-file-plugin.so: open
readonly=0
nbdkit: file[1]: debug: acquire unload prevention lock
nbdkit: file[1]: debug: get_size
nbdkit: file[1]: debug: can_write
nbdkit: file[1]: debug: can_flush
nbdkit: file[1]: debug: is_rotational
nbdkit: file[1]: debug: can_trim
nbdkit: file[1]: debug: oldstyle negotiation: flags: global 0x0 export 0x4d
nbdkit: file[1]: debug: release unload prevention lock
nbdkit: file[1]: debug: handshake complete, processing requests with 16 threads
nbdkit: nbd[1]: debug: acquire unload prevention lock
nbdkit: nbd[1]: debug: newstyle negotiation: flags: global 0x3
nbdkit: nbd[1]: debug: newstyle negotiation: client flags: 0x0
nbdkit: nbd[1]: debug: newstyle negotiation: client requested export 'wrap'
(ignored)
nbdkit: nbd[1]: debug: get_size
nbdkit: nbd[1]: debug: can_write
nbdkit: nbd[1]: debug: can_flush
nbdkit: nbd[1]: debug: is_rotational
nbdkit: nbd[1]: debug: can_trim
nbdkit: nbd[1]: debug: newstyle negotiation: flags: export 0x4d
nbdkit: nbd[1]: debug: release unload prevention lock
nbdkit: nbd[1]: debug: handshake complete, processing requests with 16 threads
nbdkit: debug: starting worker thread file.0
nbdkit: debug: starting worker thread file.1
nbdkit: debug: starting worker thread file.2
nbdkit: nbd[1]: debug: acquire unload prevention lock
nbdkit: nbd[1]: debug: get_size
nbdkit: nbd[1]: debug: can_write
nbdkit: nbd[1]: debug: can_flush
nbdkit: nbd[1]: debug: is_rotational
nbdkit: nbd[1]: debug: can_trim
nbdkit: nbd[1]: debug: nbdkit: debug: starting worker thread file.3
oldstyle negotiation: flags: global 0x0 export 0x4d
nbdkit: nbd[1]: debug: release unload prevention lock
nbdkit: nbd[1]: debug: handshake complete, processing requests with 16 threads
nbdkit: debug: starting worker thread nbd.0
nbdkit: debug: starting worker thread file.4
nbdkit: debug: starting worker thread nbd.1
nbdkit: debug: starting worker thread nbd.2
nbdkit: debug: starting worker thread file.5
nbdkit: debug: starting worker thread file.6
nbdkit: debug: starting worker thread file.7
nbdkit: debug: starting worker thread nbd.0
nbdkit: nbd.0: debug: acquire unload prevention lock
nbdkit: nbd.0: debug: pread count=512 offset=0
nbdkit: debug: starting worker thread file.8
nbdkit: nbd.0: debug: sending request with type 0 and cookie 0x900000008nbdkit: debug:
starting worker thread file.9
nbdkit: debug: starting worker thread nbd.1
nbdkit: debug: starting worker thread file.10
nbdkit: debug: starting worker thread file.11
nbdkit: nbd.0: debug: acquire unload prevention lock
nbdkit: nbd.0: debug: pread count=512 offset=0
nbdkit: debug: starting worker thread nbd.3
nbdkit: nbd.0: debug: sending request with type 0 and cookie 0x900000008
nbdkit: debug: starting worker thread nbd.4
nbdkit: debug: starting worker thread nbd.2
nbdkit: debug: starting worker thread nbd.3
nbdkit: file.0: debug: acquire unload prevention lock
nbdkit: file.0: debug: pread count=512 offset=0
nbdkit: file.0: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
==18076== Thread 3:
==18076== Invalid read of size 8
==18076== at 0x77EBB08: nbd_reply_raw (nbd.c:340)
==18076== by 0x77EBB08: nbd_reader (nbd.c:373)
==18076== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18076== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18076== Address 0x7452fc8 is 8 bytes inside a block of size 32 free'd
==18076== at 0x4C2ED18: free (vg_replace_malloc.c:530)
==18076== by 0x77EB996: nbd_reply_raw (nbd.c:337)
==18076== by 0x77EB996: nbd_reader (nbd.c:373)
==18076== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18076== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18076== Block was alloc'd at
==18076== at 0x4C2FA1E: calloc (vg_replace_malloc.c:711)
==18076== by 0x77EBBAF: nbd_request_full (nbd.c:264)
==18076== by 0x77EBD98: nbd_pread (nbd.c:602)
==18076== by 0x405986: handle_request (connections.c:884)
==18076== by 0x405986: recv_request_send_reply (connections.c:1061)
==18076== by 0x405AE7: connection_worker (connections.c:200)
==18076== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18076== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18076==
==18076== Invalid read of size 4
==18076== at 0x77EBB11: nbd_reply_raw (nbd.c:340)
==18076== by 0x77EBB11: nbd_reader (nbd.c:373)
==18076== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18076== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18076== Address 0x7452fd0 is 16 bytes inside a block of size 32 free'd
==18076== at 0x4C2ED18: free (vg_replace_malloc.c:530)
==18076== by 0x77EB996: nbd_reply_raw (nbd.c:337)
==18076== by 0x77EB996: nbd_reader (nbd.c:373)
==18076== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18076== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18076== Block was alloc'd at
==18076== at 0x4C2FA1E: calloc (vg_replace_malloc.c:711)
==18076== by 0x77EBBAF: nbd_request_full (nbd.c:264)
==18076== by 0x77EBD98: nbd_pread (nbd.c:602)
==18076== by 0x405986: handle_request (connections.c:884)
==18076== by 0x405986: recv_request_send_reply (connections.c:1061)
==18076== by 0x405AE7: connection_worker (connections.c:200)
==18076== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18076== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18076==
nbdkit: nbd.0: debug: release unload prevention lock
nbdkit: debug: starting worker thread file.12
nbdkit: debug: starting worker thread file.13
nbdkit: debug: received reply for cookie 0x900000008
==18105== Thread 3:
==18105== Invalid read of size 8
==18105== at 0x77EBB08: nbd_reply_raw (nbd.c:340)
==18105== by 0x77EBB08: nbd_reader (nbd.c:373)
==18105== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18105== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18105== Address 0x74522f8 is 8 bytes inside a block of size 32 free'd
==18105== at 0x4C2ED18: free (vg_replace_malloc.c:530)
==18105== by 0x77EB996: nbd_reply_raw (nbd.c:337)
==18105== by 0x77EB996: nbd_reader (nbd.c:373)
==18105== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18105== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18105== Block was alloc'd at
==18105== at 0x4C2FA1E: calloc (vg_replace_malloc.c:711)
==18105== by 0x77EBBAF: nbd_request_full (nbd.c:264)
==18105== by 0x77EBD98: nbd_pread (nbd.c:602)
==18105== by 0x405986: handle_request (connections.c:884)
==18105== by 0x405986: recv_request_send_reply (connections.c:1061)
==18105== by 0x405AE7: connection_worker (connections.c:200)
==18105== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18105== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18105==
==18105== Invalid read of size 4
==18105== at 0x77EBB11: nbd_reply_raw (nbd.c:340)
==18105== by 0x77EBB11: nbd_reader (nbd.c:373)
==18105== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18105== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18105== Address 0x7452300 is 16 bytes inside a block of size 32 free'd
==18105== at 0x4C2ED18: free (vg_replace_malloc.c:530)
==18105== by 0x77EB996: nbd_reply_raw (nbd.c:337)
==18105== by 0x77EB996: nbd_reader (nbd.c:373)
==18105== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18105== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18105== Block was alloc'd at
==18105== at 0x4C2FA1E: calloc (vg_replace_malloc.c:711)
==18105== by 0x77EBBAF: nbd_request_full (nbd.c:264)
==18105== by 0x77EBD98: nbd_pread (nbd.c:602)
==18105== by 0x405986: handle_request (connections.c:884)
==18105== by 0x405986: recv_request_send_reply (connections.c:1061)
==18105== by 0x405AE7: connection_worker (connections.c:200)
==18105== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18105== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18105==
nbdkit: debug: starting worker thread nbd.4
nbdkit: nbd.0: debug: release unload prevention lock
\x1blibguestfs: responding to serial console Device Status Report
[1;256r\x1b[256;256H\x1b[6n
Google, Inc.
Serial Graphics Adapter 02/12/17
SGABIOS $Id: sgabios.S 8 2010-04-22 00:03:40Z nlaredo $ (mockbuild@) Sun Feb 12 00:43:05
UTC 2017
Term: 80x24
4 0
\x1b[2J
SeaBIOS (version 1.10.2-1.fc26)
nbdkit: debug: starting worker thread nbd.5
Booting from ROM...
\x1b[2Jnbdkit: debug: starting worker thread nbd.6
nbdkit: debug: starting worker thread file.14
nbdkit: debug: starting worker thread file.15
nbdkit: debug: starting worker thread nbd.5
nbdkit: debug: starting worker thread nbd.7
nbdkit: debug: starting worker thread nbd.8
nbdkit: debug: starting worker thread nbd.6
nbdkit: debug: starting worker thread nbd.7
nbdkit: debug: starting worker thread nbd.8
nbdkit: debug: starting worker thread nbd.9
nbdkit: debug: starting worker thread nbd.10
nbdkit: debug: starting worker thread nbd.9
nbdkit: debug: starting worker thread nbd.10
nbdkit: debug: starting worker thread nbd.11
[ 0.000000] random: get_random_bytes called from start_kernel+0x42/0x4e6 with
crng_init=0
[ 0.000000] Linux version 4.13.10-200.fc26.x86_64
(mockbuild(a)bkernel01.phx2.fedoraproject.org) (gcc version 7.2.1 20170915 (Red Hat 7.2.1-2)
(GCC)) #1 SMP Fri Oct 27 15:34:40 UTC 2017
[ 0.000000] Command line: panic=1 console=ttyS0 edd=off udevtimeout=6000
udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb
cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=/dev/sdb selinux=0 guestfsnbdkit:
debug: starting worker thread nbd.12
_verbose=1 TERM=xterm-256color
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point
registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[ 0.000000] x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256
[ 0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using
'standard' format.
[ 0.000000] e820: BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009f7ff] usable
[ 0.000000] BIOS-e820: [mem 0x000000000009f800-0x000000000009ffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001f3ddfff] usable
[ 0.000000] BIOS-e820: [mem 0x000000001f3de000-0x000000001f3fffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] random: fast init done
[ 0.000000] SMBIOS 2.8 present.
[ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1.fc26
04/01/2014
[ 0.000000] Hypervisor detected: KVM
[ 0.000000] tsc: Fast TSC calibration failed
[ 0.000000] tsc: Using PIT calibration value
[ 0.000000] e820: last_pfn = 0x1f3de max_arch_pfn = 0x400000000
[ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WC UC- WT
[ 0.000000] found SMP MP-table at [mem 0x000f6c10-0x000f6c1f] mapped at
[ffff8fe6000f6c10]
[ 0.000000] Using GB pages for direct mapping
[ 0.000000] RAMDISK: [mem 0x1f346000-0x1f3cffff]
[ nbdkit: debug: starting worker thread nbd.13
0.000000] ACPI: Early table checksum verification disabled
[ 0.000000] ACPI: RSDP 0x00000000000F6A40 000014 (v00 BOCHS )
[ 0.000000] ACPI: RSDT 0x000000001F3E156F 00002C (v01 BOCHS BXPCRSDT 00000001 BXPC
00000001)
[ 0.000000] ACPI: FACP 0x000000001F3E1403 000074 (v01 BOCHS BXPCFACP 00000001 BXPC
00000001)
[ 0.000000] ACPI: DSDT 0x000000001F3E0040 0013C3 (v01 BOCHS BXPCDSDT 00000001 BXPC
00000001)
[ 0.000000] ACPI: FACS 0x000000001F3E0000 000040
[ 0.000000] ACPI: APIC 0x000000001F3E14F7 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC
00000001)
[ 0.000000] No NUMA configuration found
[ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000001f3ddfff]
[ 0.000000] NODE_DATA(0) allocated [mem 0x1f31b000-0x1f345fff]
[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[ 0.000000] kvm-clock: cpu 0, msr 0:1f29b001, primary cpu clock
[ 0.000000] kvm-clock: using sched offset of 1668979175 cycles
[ 0.000000] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb,
max_idle_ns: 881590591483 ns
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.000000] DMA32 [mem 0x0000000001000000-0x000000001f3ddfff]
[ 0.000000] Normal empty
[ 0.000000] Device empty
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff]
[ 0.000000] node 0: [mem 0x0000000000100000-0x000000001f3ddfff]
[ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x000000001f3ddfff]
[ 0.000000] ACPI: PM-Timer IO Port: 0x608
[ 0.000000] BIOS bug: APIC version is 0 for CPU 0/0x0, fixing up to 0x10
[ 0.000000] BIOS bug: APIC version mismatch, boot CPU: 0, CPU 0: version 10
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[ 0.000000] ACPI: INT_SRC_Onbdkit: debug: starting worker thread nbd.11
VR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[ 0.000000] Using ACPI (MADT) for SMP configuration information
[ 0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[ 0.000000] e820: [mem 0x1f400000-0xfeffbfff] available for PCI devices
[ 0.000000] Booting paravirtualized kernel on KVM
[ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff,
max_idle_ns: 1910969940391419 ns
[ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
[ 0.000000] percpu: Embedded 37 pages/cpu @ffff8fe61f000000 s112600 r8192 d30760
u2097152
[ 0.000000] KVM setup async PF for cpu 0
[ 0.000000] kvm-stealtime: cpu 0, msr 1f00d940
[ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages:
125847
[ 0.000000] Policy zone: DMA32
[ 0.000000] Kernel command line: panic=1 console=ttyS0 edd=off udevtimeout=6000
udev.event-timeout=6000 no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb
cryptomgr.notests tsc=reliable 8250.nr_uarts=1 root=/dev/sdb selinux=0 guestfs_verbose=1
TERM=xterm-256color
[ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)
[ 0.000000] Memory: 480652K/511472K available (8832K kernel code, 2404K rwdata, 3760K
rodata, 2224K init, 1976K bss, nbdkit: debug: starting worker thread nbd.14
30820K reserved, 0K cma-reserved)
[ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] ftrace: allocnbdkit: debug: starting worker thread nbd.15
ating 34932 entries in 137 pages
[ 0.001000] Hierarchical RCU implementation.
[ 0.001000] \tRCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=1.
[ 0.001000] \tTasks RCU enabled.
[ 0.001000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[ 0.001000] NR_IRQS: 524544, nr_irqs: 256, preallocated irqs: 16
[ 0.001000] \tOffload RCU callbacks from CPUs: .
[ 0.001000] Console: colour *CGA 80x25
[ 0.001000] console [ttyS0] enabled
[ 0.001018] tsc: Detected 2593.994 MHz processor
[ 0.002013] Calibrating delay loop (skipped) preset value.. 5187.98 BogoMIPS
(lpj=2593994)
[ 0.003012] pid_max: default: 32768 minimum: 301
[ 0.004033] ACPI: Core revision 20170531
[ 0.006285] ACPI: 1 ACPI AML tables successfully acquired and loaded
[ 0.007059] Security Framework initialized
[ 0.007520] Yama: becoming mindful.
[ nbdkit: debug: starting worker thread nbd.12
0.007914] SELinux: Disabled at boot.
[ 0.008124] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[ 0.009077] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[ 0.010025] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[ 0.011019] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[ 0.013096] Disabling memory control group subsystem
[ 0.014082] CPU: Physical Processor ID: 0
[ 0.014583] mce: CPU supports 10 MCE banks
[ 0.015047] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.015666] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
nbdkit: debug: starting worker thread nbd.13
[ 0.029447] Freeing SMP alternatives memory: 32K
[ 0.031307] smpboot: Max logical packages: 1
[ 0.031963] x2apic enabled
nbdkit: debug: starting worker thread nbd.14
[ 0.032006] Switched APIC routing to physical x2apic.
[ 0.034000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.034000] smpboot: CPU0: Intel(R) Core(TM) i7-5600U nbdkit: debug: starting worker
thread nbd.15
CPU @ 2.60GHz (family: 0x6, model: 0x3d, stepping: 0x4)
[ 0.034071] Performance Events: Broadwell events, Intel PMU driver.
[ 0.035023] ... version: 2
[ 0.035529] ... bit width: 48
[ 0.036007] ... generic registers: 4
[ 0.036475] ... value mask: 0000ffffffffffff
[ 0.037007] ... max period: 000000007fffffff
[ 0.037582] ... fixed-purpose events: 3
[ 0.038006] ... event mask: 000000070000000f
[ 0.039142] Hierarchical SRCU implementation.
[ 0.040360] smp: Bringing up secondary CPUs ...
[ 0.041007] smp: Brought up 1 node, 1 CPU
[ 0.041483] smpboot: Total of 1 processors activated (5187.98 BogoMIPS)
[ 0.042427] devtmpfs: initialized
[ 0.042950] x86/mm: Memory block size: 128MB
[ 0.043416] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns:
1911260446275000 ns
[ 0.044026] futex hash table entries: 256 (order: 2, 16384 bytes)
[ 0.044802] pinctrl core: initialized pinctrl subsystem
[ 0.045120] RTC time: 18:18:10, date: 12/02/17
[ 0.045775] NET: Registered protocol family 16
[ 0.046224] cpuidle: using governor menu
[ 0.047013] PCCT header not found.
[ 0.047582] ACPI: bus type PCI registered
[ 0.048007] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[ 0.048884] PCI: Using configuration type 1 for base access
[ 0.051287] HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
[ 0.052014] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.053046] ACPI: Added _OSI(Module Device)
[ 0.053558] ACPI: Added _OSI(Processor Device)
[ 0.054005] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.054530] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.056216] ACPI: Interpreter enabled
[ 0.056784] ACPI: (supports S0 S3 S4 S5)
[ 0.057010] ACPI: Using IOAPIC for interrupt routing
[ 0.057594] PCI: Using host bridge windows from ACPI; if necessary, use
"pci=nocrs" and report a bug
[ 0.060787] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.061013] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[ 0.061754] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[ 0.062016] acpi PNP0A03:00: fail to add MMCONFIG information, can't access
extended PCI configuration space under this bridge.
[ 0.063337] acpiphp: Slot [2] registered
[ 0.063793] acpiphp: Slot [3] registered
[ 0.064032] acpiphp: Slot [4] registered
[ 0.064484] acpiphp: Slot [5] registered
[ 0.064926] acpiphp: Slot [6] registered
[ 0.065028] acpiphp: Slot [7] registered
[ 0.065494] acpiphp: Slot [8] registered
[ 0.065970] acpiphp: Slot [9] registered
[ 0.066040] acpiphp: Slot [10] registered
[ 0.066533] acpiphp: Slot [11] registered
[ 0.067024] acpiphp: Slot [12] registered
[ 0.067507] acpiphp: Slot [13] registered
[ 0.068038] acpiphp: Slot [14] registered
[ 0.068528] acpiphp: Slot [15] registered
[ 0.069053] acpiphp: Slot [16] registered
[ 0.069556] acpiphp: Slot [17] registered
[ 0.070039] acpiphp: Slot [18] registered
[ 0.070529] acpiphp: Slot [19] registered
[ 0.071024] acpiphp: Slot [20] registered
[ 0.071517] acpiphp: Slot [21] registered
[ 0.072014] acpiphp: Slot [22] registered
[ 0.072506] acpiphp: Slot [23] registered
[ 0.072994] acpiphp: Slot [24] registered
[ 0.073042] acpiphp: Slot [25] registered
[ 0.073533] acpiphp: Slot [26] registered
[ 0.074025] acpiphp: Slot [27] registered
[ 0.074506] acpiphp: Slot [28] registered
[ 0.074983] acpiphp: Slot [29] registered
[ 0.075035] acpiphp: Slot [30] registered
[ 0.075527] acpiphp: Slot [31] registered
[ 0.076050] PCI host bridge to bus 0000:00
[ 0.076546] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window]
[ 0.077009] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 0.077756] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[ 0.078011] pci_bus 0000:00: root bus resource [mem 0x1f400000-0xfebfffff window]
[ 0.078857] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.084033] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7]
[ 0.084833] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6]
[ 0.085021] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177]
[ 0.085960] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376]
[ 0.086700] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI
[ 0.087028] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB
[ 0.117348] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[ 0.118091] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[ 0.118819] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[ 0.119096] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[ 0.119779] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[ 0.121000] ACPI: Enabled 2 GPEs in block 00 to 0F
[ 0.121131] vgaarb: loaded
[ 0.121627] SCSI subsystem initialized
[ 0.122084] usbcore: USB support disabled
[ 0.122624] EDAC MC: Ver: 3.0.0
[ 0.123181] PCI: Using ACPI for IRQ routing
[ 0.124070] NetLabel: Initializing
[ 0.124509] NetLabel: domain hash size = 128
[ 0.125005] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
[ 0.125701] NetLabel: unlabeled traffic allowed by default
[ 0.126195] clocksource: Switched to clocksource kvm-clock
[ 0.179195] VFS: Disk quotas dquot_6.6.0
[ 0.179723] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 0.180753] pnp: PnP ACPI init
[ 0.181715] pnp: PnP ACPI: found 5 devices
[ 0.194906] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns:
2085701024 ns
[ 0.196271] NET: Registered protocol family 2
[ 0.197083] TCP established hash table entries: 4096 (order: 3, 32768 bytes)
[ 0.197938] TCP bind hash table entries: 4096 (order: 4, 65536 bytes)
[ 0.198710] TCP: Hash tables configured (established 4096 bind 4096)
[ 0.199456] UDP hash table entries: 256 (order: 1, 8192 bytes)
[ 0.200089] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes)
[ 0.200794] NET: Registered protocol family 1
[ 0.201266] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[ 0.201934] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[ 0.202586] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[ 0.203357] Unpacking initramfs...
[ 0.204848] Freeing initrd memory: 552K
[ 0.205719] alg: self-tests disabled
[ 0.206329] audit: initializing netlink subsys (disabled)
[ 0.207326] Initialise system trusted keyrings
[ 0.207859] Key type blacklist registered
[ 0.208359] audit: type=2000 audit(1512238691.313:1): state=initialized audit_enabled=0
res=1
[ 0.209270] workingset: timestamp_bits=36 max_order=17 bucket_order=0
[ 0.211488] zbud: loaded
[ 0.213866] NET: Registered protocol family 38
[ 0.214388] Key type asymmetric registered
[ 0.214830] Asymmetric key parser 'x509' registered
[ 0.215505] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[ 0.216410] io scheduler noop registered
[ 0.216867] io scheduler deadline registered
[ 0.217501] io scheduler cfq registered (default)
[ 0.218063] io scheduler mq-deadline registered
[ 0.218671] atomic64_test: passed for x86-64 platform with CX8 and with SSE
[ 0.219771] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[ 0.220674] ACPI: Power Button [PWRF]
[ 0.221322] GHES: HEST is not enabled!
[ 0.221878] Serial: 8250/16550 driver, 1 ports, IRQ sharing enabled
[ 0.247931] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 0.249137] Non-volatile memory driver v1.3
[ 0.249622] Linux agpgart interface v0.103
[ 0.251430] scsi host0: ata_piix
[ 0.251945] scsi host1: ata_piix
[ 0.252413] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0a0 irq 14
[ 0.253789] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0a8 irq 15
[ 0.254696] libphy: Fixed MDIO Bus: probed
[ 0.255387] usbserial: usb_serial_init - usb_register failed
[ 0.256070] usbserial: usb_serial_init - returning with error -19
[ 0.256877] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq
1,12
[ 0.258536] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 0.259117] serio: i8042 AUX port at 0x60,0x64 irq 12
[ 0.259762] mousedev: PS/2 mouse device common for all mice
[ 0.260632] rtc_cmos 00:00: RTC can wake from S4
[ 0.261453] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[ 0.262258] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram
[ 0.263145] device-mapper: uevent: version 1.0.3
[ 0.263743] device-mapper: ioctl: 4.37.0-ioctl (2017-09-20) initialised:
dm-devel(a)redhat.com
[ 0.270804] input: AT Translated Set 2 keyboard as
/devices/platform/i8042/serio0/input/input1
[ 0.280238] input: VirtualPS/2 VMware VMMouse as
/devices/platform/i8042/serio1/input/input4
[ 0.281482] input: VirtualPS/2 VMware VMMouse as
/devices/platform/i8042/serio1/input/input3
[ 0.282926] hidraw: raw HID events driver (C) Jiri Kosina
[ 0.283696] drop_monitor: Initializing network drop monitor service
[ 0.288738] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 0.289376] Initializing XFRM netlink socket
[ 0.290042] NET: Registered protocol family 10
[ 0.291335] Segment Routing with IPv6
[ 0.291760] mip6: Mobile IPv6
[ 0.295287] NET: Registered protocol family 17
[ 0.296065] RAS: Correctable Errors collector initialized.
[ 0.296688] AVX2 version of gcm_enc/dec engaged.
[ 0.297234] AES CTR mode by8 optimization enabled
[ 0.298065] sched_clock: Marking stable (298006939, 0)->(963793643, -665786704)
[ 0.301614] registered taskstats version 1
[ 0.302185] Loading compiled-in X.509 certificates
[ 0.303798] Loaded X.509 cert 'Fedora kernel signing key:
51c174b3b29f2bca25259469ca1630e13d5d4e40'
[ 0.304866] zswap: loaded using pool lzo/zbud
[ 0.306061] Key type big_key registered
[ 0.306601] Key type encrypted registered
[ 0.307360] Magic number: 13:367:341
[ 0.307718] rtc_cmos 00:00: setting system clock to 2017-12-02 18:18:11 UTC
(1512238691)
[ 0.451314] Freeing unused kernel memory: 2224K
[ 0.451930] Write protecting the kernel read-only data: 14336k
[ 0.453088] Freeing unused kernel memory: 1396K
[ 0.455261] Freeing unused kernel memory: 336K
[ 0.458145] x86/mm: Checked W+X mappings: passed, no W+X pages found.
[ 0.462954] rodata_test: all tests were successful
supermin: mounting /proc
supermin: ext2 mini initrd starting up: 5.1.19 dietlibc
supermin: cmdline: panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000
no_timer_check printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests
tsc=reliable 8250.nr_uarts=1 root=/dev/sdb selinux=0 guestfs_verbose=1
TERM=xterm-256color
supermin: uptime: 0.46 0.17
supermin: mounting /sys
supermin: internal insmod crc32-pclmul.ko
supermin: internal insmod crc32c-intel.ko
supermin: internal insmod crct10dif-pclmul.ko
supermin: internal insmod crc32_generic.ko
supermin: internal insmod nfit.ko
supermin: internal insmod virtio.ko
supermin: internal insmod virtio_ring.ko
supermin: internal insmod virtio_blk.ko
supermin: internal insmod virtio-rng.ko
supermin: internal insmod virtio_console.ko
supermin: internal insmod crypto_engine.ko
supermin: internal insmod virtio_crypto.ko
supermin: internal insmod virtio_net.ko
supermin: internal insmod nd_btt.ko
supermin: internal insmod nd_pmem.ko
supermin: internal insmod virtio_scsi.ko
supermin: internal insmod virtio_balloon.ko
supermin: internal insmod virtio_input.ko
supermin: internal insmod virtio_mmio.ko
supermin: internal insmod virtio_pci.ko
[ 0.546186] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 10
[ 0.611483] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[ 0.616020] scsi host2: Virtio SCSI HBA
[ 0.694682] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11
[ 0.725435] scsi 2:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI:
5
[ 0.726642] scsi 2:0:1:0: Direct-Access QEMU QEMU HARDDISK 2.5+ PQ: 0 ANSI:
5
[ 0.773695] random: crng init done
[ 0.794300] sd 2:0:0:0: Attached scsi generic sg0 type 0
[ 0.795201] sd 2:0:1:0: Attached scsi generic sg1 type 0
[ 0.796099] sd 2:0:0:0: [sda] 8 512-byte logical blocks: (4.10 kB/4.00 KiB)
[ 0.809355] sd 2:0:0:0: [sda] Write Protect is off
[ 0.809997] sd 2:0:1:0: [sdb] 8388608 512-byte logical blocks: (4.29 GB/4.00 GiB)
[ 0.810842] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't
support DPO or FUA
[ 0.811980] sd 2:0:1:0: [sdb] Write Protect is off
[ 0.822165] sd 2:0:1:0: [sdb] Write cache: enabled, read cache: ennbdkit: nbd.1: debug:
acquire unload prevention lock
nbdkit: nbd.1: debug: pread count=4096 offset=0
nbdkit: nbd.1: debug: sending request with type 0 and cookie 0x900000008
abled, doesn't support DPO or FUA
nbdkit: nbd.1: debug: acquire unload prevention lock
nbdkit: nbd.1: debug: pread count=4096 offset=0
nbdkit: nbd.1: debug: sending request with type 0 and cookie 0x900000008
nbdkit: file.1: debug: acquire unload prevention lock
nbdkit: file.1: debug: pread count=4096 offset=0
nbdkit: file.1: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.1: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.1: debug: release unload prevention lock
[ 0.827525] sd 2:0:1:0: [sdb] Attached SCSI disk
[ 0.828283] Dev sda: unable to read RDB block 8
[ 0.828930] sda: unable to read partition table
[ 0.829520] sda: partition table beyond EOD, enabling native capacity
nbdkit: nbd.2: debug: acquire unload prevention lock
nbdkit: nbd.2: debug: pread count=4096 offset=0
nbdkit: nbd.2: debug: sending request with type 0 and cookie 0x900000008
nbdkit: nbd.2: debug: acquire unload prevention lock
nbdkit: nbd.2: debug: pread count=4096 offset=0
nbdkit: nbd.2: debug: sending request with type 0 and cookie 0x900000008
nbdkit: file.1: debug: acquire unload prevention lock
nbdkit: file.1: debug: pread count=4096 offset=0
nbdkit: file.1: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.2: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.2: debug: release unload prevention lock
[ 0.843392] Dev sda: unable to read RDB block 8
[ 0.843920] sda: unable to read partition table
[ 0.844655] sda: partition table beyond EOD, truncated
[ 0.847783] sd 2:0:0:0: [sda] Attached SCSI disk
supermin: internal insmod crc-itu-t.ko
supermin: internal insmod crc4.ko
supermin: internal insmod crc7.ko
supermin: internal insmod crc8.ko
supermin: internal insmod libcrc32c.ko
supermin: picked /sys/block/sdb/dev (8:16) as root device
supermin: creating /dev/root as block special 8:16
supermin: mounting new root on /root
[ 0.859706] EXT4-fs (sdb): mounting ext2 file system using the ext4 subsystem
[ 0.875937] EXT4-fs (sdb): mounted filesystem without journal. Opts:
supermin: deleting initramfs files
supermin: chroot
Starting /init script ...
+ [[ panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check
printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable
8250.nr_uarts=1 root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color ==
*guestfs_network=1* ]]
+ [[ panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check
printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable
8250.nr_uarts=1 root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color ==
*guestfs_rescue=1* ]]
+ [[ panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check
printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable
8250.nr_uarts=1 root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color ==
*guestfs_noreboot=1* ]]
+ [[ panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check
printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable
8250.nr_uarts=1 root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color ==
*guestfs_boot_analysis=1* ]]
+ '[' '!' -d /sys ']'
+ mkdir -p /sys
+ mount -t sysfs /sys /sys
+ mkdir -p /run
+ mount -t tmpfs -o nosuid,size=20%,mode=0755 tmpfs /run
+ mkdir -p /run/lock
+ ln -s ../run/lock /var/lock
+ test -e /etc/mtab
+ ln -s /proc/mounts /etc/mtab
+ mount -t devtmpfs /dev /dev
+ mkdir -p /dev/pts
+ mount -t devpts /dev/pts /dev/pts
+ [[ panic=1 console=ttyS0 edd=off udevtimeout=6000 udev.event-timeout=6000 no_timer_check
printk.time=1 cgroup_disable=memory usbcore.nousb cryptomgr.notests tsc=reliable
8250.nr_uarts=1 root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm-256color ==
*selinux=1* ]]
+ mkdir -p /run/tmpfiles.d
+ kmod static-nodes --format=tmpfiles --output=/run/tmpfiles.d/kmod.conf
++ od -x -A n
++ dd if=/dev/urandom bs=16 count=1 status=none
+ machine_id=' d1e7 b532 ceb8 1e56 e068 ee3e 9e7b 76eb'
+ echo d1e7b532ceb81e56e068ee3e9e7b76eb
+ systemd-tmpfiles --prefix=/dev --prefix=/run --prefix=/var/run --create --boot
[/usr/lib/tmpfiles.d/systemd.conf:11] Unknown group 'utmp'.
[/usr/lib/tmpfiles.d/systemd.conf:19] Unknown user 'systemd-network'.
[/usr/lib/tmpfiles.d/systemd.conf:20] Unknown user 'systemd-network'.
[/usr/lib/tmpfiles.d/systemd.conf:21] Unknown user 'systemd-network'.
[/usr/lib/tmpfiles.d/systemd.conf:25] Unknown group 'systemd-journal'.
[/usr/lib/tmpfiles.d/systemd.conf:26] Unknown group 'systemd-journal'.
+ for f in /lib/systemd/systemd-udevd /usr/lib/systemd/systemd-udevd /sbin/udevd
/lib/udev/udevd /usr/lib/udev/udevd
+ '[' -x /lib/systemd/systemd-udevd ']'
+ UDEVD=/lib/systemd/systemd-udevd
+ break
+ '[' -z /lib/systemd/systemd-udevd ']'
+ /lib/systemd/systemd-udevd --daemon
starting version 233
+ udevadm trigger
+ udevadm settle --timeout=600
[ 1.325804] tsc: Refined TSC clocksource calibration: 2593.997 MHz
[ 1.326601] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x25641593871,
max_idle_ns: 440795323545 ns
[ 1.530638] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[ 1.546355] ppdev: user-space parallel port driver
nbdkit: nbd.3: debug: acquire unload prevention lock
nbdkit: nbd.3: debug: pread count=4096 offset=0
nbdkit: nbd.3: debug: sending request with type 0 and cookie 0x900000008
nbdkit: nbd.3: debug: acquire unload prevention lock
nbdkit: nbd.3: debug: pread count=4096 offset=0
nbdkit: nbd.3: debug: sending request with type 0 and cookie 0x900000008
nbdkit: file.1: debug: acquire unload prevention lock
nbdkit: file.1: debug: pread count=4096 offset=0
nbdkit: file.1: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.3: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.3: debug: release unload prevention lock
[ 1.608491] input: PC Speaker as /devices/platform/pcspkr/input/input5
nbdkit: nbd.4: debug: acquire unload prevention lock
nbdkit: nbd.4: debug: pread count=4096 offset=0
nbdkit: nbd.4: debug: sending request with type 0 and cookie 0x900000008
nbdkit: nbd.4: debug: acquire unload prevention lock
nbdkit: nbd.4: debug: pread count=4096 offset=0
nbdkit: nbd.4: debug: sending request with type 0 and cookie 0x900000008
nbdkit: file.1: debug: acquire unload prevention lock
nbdkit: file.1: debug: pread count=4096 offset=0
nbdkit: file.1: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.4: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.4: debug: release unload prevention lock
+ shopt -s nullglob
+ for f in /sys/block/sd*/device/timeout
+ echo 300
+ for f in /sys/block/sd*/device/timeout
+ echo 300
+ for f in /sys/block/{h,s,ub,v}d*/queue/scheduler
+ echo noop
+ for f in /sys/block/{h,s,ub,v}d*/queue/scheduler
+ echo noop
+ shopt -u nullglob
+ ip addr add 127.0.0.1/8 brd + dev lo scope host
+ ip link set dev lo up
+ test '' = 1
+ mdadm -As --auto=yes --run
nbdkit: nbd.0: debug: acquire unload prevention lock
nbdkit: nbd.0: debug: pread count=512 offset=3072
nbdkit: nbd.0: debug: sending request with type 0 and cookie 0x900000008
nbdkit: nbd.0: debug: acquire unload prevention lock
nbdkit: nbd.0: debug: pread count=512 offset=3072
nbdkit: nbd.0: debug: sending request with type 0 and cookie 0x900000008
nbdkit: file.1: debug: acquire unload prevention lock
nbdkit: file.1: debug: pread count=512 offset=3072
nbdkit: file.1: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.0: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.0: debug: release unload prevention lock
nbdkit: nbd.5: debug: acquire unload prevention lock
nbdkit: nbd.5: debug: pread count=512 offset=0
nbdkit: nbd.5: debug: sending request with type 0 and cookie 0x900000008
nbdkit: nbd.5: debug: acquire unload prevention lock
nbdkit: nbd.5: debug: pread count=512 offset=0
nbdkit: nbd.5: debug: sending request with type 0 and cookie 0x900000008
nbdkit: file.1: debug: acquire unload prevention lock
nbdkit: file.1: debug: pread count=512 offset=0
nbdkit: file.1: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.5: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.5: debug: release unload prevention lock
nbdkit: nbd.6: debug: acquire unload prevention lock
nbdkit: nbd.6: debug: pread count=512 offset=0
nbdkit: nbd.6: debug: sending request with type 0 and cookie 0x900000008
nbdkit: nbd.6: debug: acquire unload prevention lock
nbdkit: nbd.6: debug: pread count=512 offset=0
nbdkit: nbd.6: debug: sending request with type 0 and cookie 0x900000008
nbdkit: file.1: debug: acquire unload prevention lock
nbdkit: file.1: debug: pread count=512 offset=0
nbdkit: file.1: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.6: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.6: debug: release unload prevention lock
mdadm: No arrays found in config file or automatically
+ modprobe dm_mod
+ lvm vgchange -aay --sysinit
WARNING: Failed to connect to lvmetad. Falling back to device scanning.
+ ldmtool create all
nbdkit: nbd.7: debug: acquire unload prevention lock
nbdkit: nbd.7: debug: pread count=4096 offset=0
nbdkit: nbd.7: debug: sending request with type 0 and cookie 0x900000008
nbdkit: nbd.7: debug: acquire unload prevention lock
nbdkit: nbd.7: debug: pread count=4096 offset=0
nbdkit: nbd.7: debug: sending request with type 0 and cookie 0x900000008
nbdkit: file.1: debug: acquire unload prevention lock
nbdkit: file.1: debug: pread count=4096 offset=0
nbdkit: file.1: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.7: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.7: debug: release unload prevention lock
[
]
+ test 1 = 1
+ test '' '!=' 1
+ uname -a
Linux (none) 4.13.10-200.fc26.x86_64 #1 SMP Fri Oct 27 15:34:40 UTC 2017 x86_64 x86_64
x86_64 GNU/Linux
+ ls -lR /dev
/dev:
total 0
crw------- 1 root root 10, 235 Dec 2 18:18 autofs
drwxr-xr-x 2 root root 80 Dec 2 18:18 block
drwxr-xr-x 2 root root 80 Dec 2 18:18 bsg
crw------- 1 root root 10, 234 Dec 2 18:18 btrfs-control
drwxr-xr-x 2 root root 2300 Dec 2 18:18 char
crw------- 1 root root 5, 1 Dec 2 18:18 console
lrwxrwxrwx 1 root root 11 Dec 2 18:18 core -> /proc/kcore
drwxr-xr-x 3 root root 60 Dec 2 18:18 cpu
crw------- 1 root root 10, 62 Dec 2 18:18 cpu_dma_latency
drwxr-xr-x 5 root root 100 Dec 2 18:18 disk
lrwxrwxrwx 1 root root 13 Dec 2 18:18 fd -> /proc/self/fd
crw-rw-rw- 1 root root 1, 7 Dec 2 18:18 full
crw-rw-rw- 1 root root 10, 229 Dec 2 18:18 fuse
crw------- 1 root root 10, 228 Dec 2 18:18 hpet
crw------- 1 root root 10, 183 Dec 2 18:18 hwrng
drwxr-xr-x 3 root root 220 Dec 2 18:18 input
crw-r--r-- 1 root root 1, 11 Dec 2 18:18 kmsg
crw-rw---- 1 root disk 10, 237 Dec 2 18:18 loop-control
drwxr-xr-x 2 root root 60 Dec 2 18:18 mapper
crw------- 1 root root 10, 227 Dec 2 18:18 mcelog
crw------- 1 root root 1, 1 Dec 2 18:18 mem
crw------- 1 root root 10, 59 Dec 2 18:18 memory_bandwidth
drwxr-xr-x 2 root root 60 Dec 2 18:18 net
crw------- 1 root root 10, 61 Dec 2 18:18 network_latency
crw------- 1 root root 10, 60 Dec 2 18:18 network_throughput
crw-rw-rw- 1 root root 1, 3 Dec 2 18:18 null
crw------- 1 root root 10, 144 Dec 2 18:18 nvram
crw------- 1 root root 1, 4 Dec 2 18:18 port
crw------- 1 root root 108, 0 Dec 2 18:18 ppp
crw-rw-rw- 1 root root 5, 2 Dec 2 18:18 ptmx
drwxr-xr-x 2 root root 0 Dec 2 18:18 pts
crw-rw-rw- 1 root root 1, 8 Dec 2 18:18 random
drwxr-xr-x 2 root root 60 Dec 2 18:18 raw
lrwxrwxrwx 1 root root 4 Dec 2 18:18 rtc -> rtc0
crw------- 1 root root 250, 0 Dec 2 18:18 rtc0
brw------- 1 root root 8, 0 Dec 2 18:18 sda
brw------- 1 root root 8, 16 Dec 2 18:18 sdb
crw------- 1 root root 21, 0 Dec 2 18:18 sg0
crw------- 1 root root 21, 1 Dec 2 18:18 sg1
crw------- 1 root root 10, 231 Dec 2 18:18 snapshot
drwxr-xr-x 3 root root 140 Dec 2 18:18 snd
lrwxrwxrwx 1 root root 15 Dec 2 18:18 stderr -> /proc/self/fd/2
lrwxrwxrwx 1 root root 15 Dec 2 18:18 stdin -> /proc/self/fd/0
lrwxrwxrwx 1 root root 15 Dec 2 18:18 stdout -> /proc/self/fd/1
crw-rw-rw- 1 root root 5, 0 Dec 2 18:18 tty
crw------- 1 root root 4, 0 Dec 2 18:18 tty0
crw------- 1 root root 4, 1 Dec 2 18:18 tty1
crw------- 1 root root 4, 10 Dec 2 18:18 tty10
crw------- 1 root root 4, 11 Dec 2 18:18 tty11
crw------- 1 root root 4, 12 Dec 2 18:18 tty12
crw------- 1 root root 4, 13 Dec 2 18:18 tty13
crw------- 1 root root 4, 14 Dec 2 18:18 tty14
crw------- 1 root root 4, 15 Dec 2 18:18 tty15
crw------- 1 root root 4, 16 Dec 2 18:18 tty16
crw------- 1 root root 4, 17 Dec 2 18:18 tty17
crw------- 1 root root 4, 18 Dec 2 18:18 tty18
crw------- 1 root root 4, 19 Dec 2 18:18 tty19
crw------- 1 root root 4, 2 Dec 2 18:18 tty2
crw------- 1 root root 4, 20 Dec 2 18:18 tty20
crw------- 1 root root 4, 21 Dec 2 18:18 tty21
crw------- 1 root root 4, 22 Dec 2 18:18 tty22
crw------- 1 root root 4, 23 Dec 2 18:18 tty23
crw------- 1 root root 4, 24 Dec 2 18:18 tty24
crw------- 1 root root 4, 25 Dec 2 18:18 tty25
crw------- 1 root root 4, 26 Dec 2 18:18 tty26
crw------- 1 root root 4, 27 Dec 2 18:18 tty27
crw------- 1 root root 4, 28 Dec 2 18:18 tty28
crw------- 1 root root 4, 29 Dec 2 18:18 tty29
crw------- 1 root root 4, 3 Dec 2 18:18 tty3
crw------- 1 root root 4, 30 Dec 2 18:18 tty30
crw------- 1 root root 4, 31 Dec 2 18:18 tty31
crw------- 1 root root 4, 32 Dec 2 18:18 tty32
crw------- 1 root root 4, 33 Dec 2 18:18 tty33
crw------- 1 root root 4, 34 Dec 2 18:18 tty34
crw------- 1 root root 4, 35 Dec 2 18:18 tty35
crw------- 1 root root 4, 36 Dec 2 18:18 tty36
crw------- 1 root root 4, 37 Dec 2 18:18 tty37
crw------- 1 root root 4, 38 Dec 2 18:18 tty38
crw------- 1 root root 4, 39 Dec 2 18:18 tty39
crw------- 1 root root 4, 4 Dec 2 18:18 tty4
crw------- 1 root root 4, 40 Dec 2 18:18 tty40
crw------- 1 root root 4, 41 Dec 2 18:18 tty41
crw------- 1 root root 4, 42 Dec 2 18:18 tty42
crw------- 1 root root 4, 43 Dec 2 18:18 tty43
crw------- 1 root root 4, 44 Dec 2 18:18 tty44
crw------- 1 root root 4, 45 Dec 2 18:18 tty45
crw------- 1 root root 4, 46 Dec 2 18:18 tty46
crw------- 1 root root 4, 47 Dec 2 18:18 tty47
crw------- 1 root root 4, 48 Dec 2 18:18 tty48
crw------- 1 root root 4, 49 Dec 2 18:18 tty49
crw------- 1 root root 4, 5 Dec 2 18:18 tty5
crw------- 1 root root 4, 50 Dec 2 18:18 tty50
crw------- 1 root root 4, 51 Dec 2 18:18 tty51
crw------- 1 root root 4, 52 Dec 2 18:18 tty52
crw------- 1 root root 4, 53 Dec 2 18:18 tty53
crw------- 1 root root 4, 54 Dec 2 18:18 tty54
crw------- 1 root root 4, 55 Dec 2 18:18 tty55
crw------- 1 root root 4, 56 Dec 2 18:18 tty56
crw------- 1 root root 4, 57 Dec 2 18:18 tty57
crw------- 1 root root 4, 58 Dec 2 18:18 tty58
crw------- 1 root root 4, 59 Dec 2 18:18 tty59
crw------- 1 root root 4, 6 Dec 2 18:18 tty6
crw------- 1 root root 4, 60 Dec 2 18:18 tty60
crw------- 1 root root 4, 61 Dec 2 18:18 tty61
crw------- 1 root root 4, 62 Dec 2 18:18 tty62
crw------- 1 root root 4, 63 Dec 2 18:18 tty63
crw------- 1 root root 4, 7 Dec 2 18:18 tty7
crw------- 1 root root 4, 8 Dec 2 18:18 tty8
crw------- 1 root root 4, 9 Dec 2 18:18 tty9
crw------- 1 root root 4, 64 Dec 2 18:18 ttyS0
crw------- 1 root root 10, 239 Dec 2 18:18 uhid
crw------- 1 root root 10, 223 Dec 2 18:18 uinput
crw-rw-rw- 1 root root 1, 9 Dec 2 18:18 urandom
crw------- 1 root root 247, 0 Dec 2 18:18 usbmon0
crw------- 1 root root 7, 0 Dec 2 18:18 vcs
crw------- 1 root root 7, 1 Dec 2 18:18 vcs1
crw------- 1 root root 7, 128 Dec 2 18:18 vcsa
crw------- 1 root root 7, 129 Dec 2 18:18 vcsa1
drwxr-xr-x 2 root root 60 Dec 2 18:18 vfio
crw------- 1 root root 10, 63 Dec 2 18:18 vga_arbiter
crw------- 1 root root 10, 137 Dec 2 18:18 vhci
crw------- 1 root root 10, 238 Dec 2 18:18 vhost-net
crw------- 1 root root 10, 241 Dec 2 18:18 vhost-vsock
drwxr-xr-x 2 root root 60 Dec 2 18:18 virtio-ports
crw------- 1 root root 245, 1 Dec 2 18:18 vport2p1
crw-rw-rw- 1 root root 1, 5 Dec 2 18:18 zero
/dev/block:
total 0
lrwxrwxrwx 1 root root 6 Dec 2 18:18 8:0 -> ../sda
lrwxrwxrwx 1 root root 6 Dec 2 18:18 8:16 -> ../sdb
/dev/bsg:
total 0
crw------- 1 root root 248, 0 Dec 2 18:18 2:0:0:0
crw------- 1 root root 248, 1 Dec 2 18:18 2:0:1:0
/dev/char:
total 0
lrwxrwxrwx 1 root root 8 Dec 2 18:18 10:144 -> ../nvram
lrwxrwxrwx 1 root root 8 Dec 2 18:18 10:183 -> ../hwrng
lrwxrwxrwx 1 root root 9 Dec 2 18:18 10:227 -> ../mcelog
lrwxrwxrwx 1 root root 7 Dec 2 18:18 10:228 -> ../hpet
lrwxrwxrwx 1 root root 11 Dec 2 18:18 10:231 -> ../snapshot
lrwxrwxrwx 1 root root 9 Dec 2 18:18 10:235 -> ../autofs
lrwxrwxrwx 1 root root 17 Dec 2 18:18 10:236 -> ../mapper/control
lrwxrwxrwx 1 root root 19 Dec 2 18:18 10:59 -> ../memory_bandwidth
lrwxrwxrwx 1 root root 21 Dec 2 18:18 10:60 -> ../network_throughput
lrwxrwxrwx 1 root root 18 Dec 2 18:18 10:61 -> ../network_latency
lrwxrwxrwx 1 root root 18 Dec 2 18:18 10:62 -> ../cpu_dma_latency
lrwxrwxrwx 1 root root 14 Dec 2 18:18 10:63 -> ../vga_arbiter
lrwxrwxrwx 1 root root 16 Dec 2 18:18 116:2 -> ../snd/controlC0
lrwxrwxrwx 1 root root 15 Dec 2 18:18 116:3 -> ../snd/pcmC0D0p
lrwxrwxrwx 1 root root 12 Dec 2 18:18 116:33 -> ../snd/timer
lrwxrwxrwx 1 root root 15 Dec 2 18:18 13:32 -> ../input/mouse0
lrwxrwxrwx 1 root root 15 Dec 2 18:18 13:33 -> ../input/mouse1
lrwxrwxrwx 1 root root 13 Dec 2 18:18 13:63 -> ../input/mice
lrwxrwxrwx 1 root root 15 Dec 2 18:18 13:64 -> ../input/event0
lrwxrwxrwx 1 root root 15 Dec 2 18:18 13:65 -> ../input/event1
lrwxrwxrwx 1 root root 15 Dec 2 18:18 13:66 -> ../input/event2
lrwxrwxrwx 1 root root 15 Dec 2 18:18 13:67 -> ../input/event3
lrwxrwxrwx 1 root root 15 Dec 2 18:18 13:68 -> ../input/event4
lrwxrwxrwx 1 root root 13 Dec 2 18:18 162:0 -> ../raw/rawctl
lrwxrwxrwx 1 root root 6 Dec 2 18:18 1:1 -> ../mem
lrwxrwxrwx 1 root root 7 Dec 2 18:18 1:11 -> ../kmsg
lrwxrwxrwx 1 root root 7 Dec 2 18:18 1:3 -> ../null
lrwxrwxrwx 1 root root 7 Dec 2 18:18 1:4 -> ../port
lrwxrwxrwx 1 root root 7 Dec 2 18:18 1:5 -> ../zero
lrwxrwxrwx 1 root root 7 Dec 2 18:18 1:7 -> ../full
lrwxrwxrwx 1 root root 9 Dec 2 18:18 1:8 -> ../random
lrwxrwxrwx 1 root root 10 Dec 2 18:18 1:9 -> ../urandom
lrwxrwxrwx 1 root root 12 Dec 2 18:18 202:0 -> ../cpu/0/msr
lrwxrwxrwx 1 root root 14 Dec 2 18:18 203:0 -> ../cpu/0/cpuid
lrwxrwxrwx 1 root root 6 Dec 2 18:18 21:0 -> ../sg0
lrwxrwxrwx 1 root root 6 Dec 2 18:18 21:1 -> ../sg1
lrwxrwxrwx 1 root root 11 Dec 2 18:18 245:1 -> ../vport2p1
lrwxrwxrwx 1 root root 10 Dec 2 18:18 247:0 -> ../usbmon0
lrwxrwxrwx 1 root root 14 Dec 2 18:18 248:0 -> ../bsg/2:0:0:0
lrwxrwxrwx 1 root root 14 Dec 2 18:18 248:1 -> ../bsg/2:0:1:0
lrwxrwxrwx 1 root root 7 Dec 2 18:18 250:0 -> ../rtc0
lrwxrwxrwx 1 root root 7 Dec 2 18:18 4:0 -> ../tty0
lrwxrwxrwx 1 root root 7 Dec 2 18:18 4:1 -> ../tty1
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:10 -> ../tty10
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:11 -> ../tty11
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:12 -> ../tty12
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:13 -> ../tty13
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:14 -> ../tty14
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:15 -> ../tty15
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:16 -> ../tty16
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:17 -> ../tty17
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:18 -> ../tty18
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:19 -> ../tty19
lrwxrwxrwx 1 root root 7 Dec 2 18:18 4:2 -> ../tty2
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:20 -> ../tty20
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:21 -> ../tty21
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:22 -> ../tty22
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:23 -> ../tty23
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:24 -> ../tty24
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:25 -> ../tty25
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:26 -> ../tty26
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:27 -> ../tty27
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:28 -> ../tty28
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:29 -> ../tty29
lrwxrwxrwx 1 root root 7 Dec 2 18:18 4:3 -> ../tty3
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:30 -> ../tty30
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:31 -> ../tty31
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:32 -> ../tty32
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:33 -> ../tty33
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:34 -> ../tty34
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:35 -> ../tty35
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:36 -> ../tty36
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:37 -> ../tty37
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:38 -> ../tty38
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:39 -> ../tty39
lrwxrwxrwx 1 root root 7 Dec 2 18:18 4:4 -> ../tty4
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:40 -> ../tty40
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:41 -> ../tty41
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:42 -> ../tty42
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:43 -> ../tty43
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:44 -> ../tty44
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:45 -> ../tty45
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:46 -> ../tty46
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:47 -> ../tty47
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:48 -> ../tty48
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:49 -> ../tty49
lrwxrwxrwx 1 root root 7 Dec 2 18:18 4:5 -> ../tty5
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:50 -> ../tty50
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:51 -> ../tty51
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:52 -> ../tty52
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:53 -> ../tty53
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:54 -> ../tty54
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:55 -> ../tty55
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:56 -> ../tty56
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:57 -> ../tty57
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:58 -> ../tty58
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:59 -> ../tty59
lrwxrwxrwx 1 root root 7 Dec 2 18:18 4:6 -> ../tty6
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:60 -> ../tty60
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:61 -> ../tty61
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:62 -> ../tty62
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:63 -> ../tty63
lrwxrwxrwx 1 root root 8 Dec 2 18:18 4:64 -> ../ttyS0
lrwxrwxrwx 1 root root 7 Dec 2 18:18 4:7 -> ../tty7
lrwxrwxrwx 1 root root 7 Dec 2 18:18 4:8 -> ../tty8
lrwxrwxrwx 1 root root 7 Dec 2 18:18 4:9 -> ../tty9
lrwxrwxrwx 1 root root 6 Dec 2 18:18 5:0 -> ../tty
lrwxrwxrwx 1 root root 10 Dec 2 18:18 5:1 -> ../console
lrwxrwxrwx 1 root root 7 Dec 2 18:18 5:2 -> ../ptmx
lrwxrwxrwx 1 root root 6 Dec 2 18:18 7:0 -> ../vcs
lrwxrwxrwx 1 root root 7 Dec 2 18:18 7:1 -> ../vcs1
lrwxrwxrwx 1 root root 7 Dec 2 18:18 7:128 -> ../vcsa
lrwxrwxrwx 1 root root 8 Dec 2 18:18 7:129 -> ../vcsa1
/dev/cpu:
total 0
drwxr-xr-x 2 root root 80 Dec 2 18:18 0
/dev/cpu/0:
total 0
crw------- 1 root root 203, 0 Dec 2 18:18 cpuid
crw------- 1 root root 202, 0 Dec 2 18:18 msr
/dev/disk:
total 0
drwxr-xr-x 2 root root 80 Dec 2 18:18 by-id
drwxr-xr-x 2 root root 80 Dec 2 18:18 by-path
drwxr-xr-x 2 root root 60 Dec 2 18:18 by-uuid
/dev/disk/by-id:
total 0
lrwxrwxrwx 1 root root 9 Dec 2 18:18 scsi-0QEMU_QEMU_HARDDISK_appliance -> ../../sdb
lrwxrwxrwx 1 root root 9 Dec 2 18:18 scsi-0QEMU_QEMU_HARDDISK_hd0 -> ../../sda
/dev/disk/by-path:
total 0
lrwxrwxrwx 1 root root 9 Dec 2 18:18 pci-0000:00:03.0-scsi-0:0:0:0 -> ../../sda
lrwxrwxrwx 1 root root 9 Dec 2 18:18 pci-0000:00:03.0-scsi-0:0:1:0 -> ../../sdb
/dev/disk/by-uuid:
total 0
lrwxrwxrwx 1 root root 9 Dec 2 18:18 30008a2e-77c6-49a2-9283-7ee454762d7b ->
../../sdb
/dev/input:
total 0
drwxr-xr-x 2 root root 60 Dec 2 18:18 by-path
crw------- 1 root root 13, 64 Dec 2 18:18 event0
crw------- 1 root root 13, 65 Dec 2 18:18 event1
crw------- 1 root root 13, 66 Dec 2 18:18 event2
crw------- 1 root root 13, 67 Dec 2 18:18 event3
crw------- 1 root root 13, 68 Dec 2 18:18 event4
crw------- 1 root root 13, 63 Dec 2 18:18 mice
crw------- 1 root root 13, 32 Dec 2 18:18 mouse0
crw------- 1 root root 13, 33 Dec 2 18:18 mouse1
/dev/input/by-path:
total 0
lrwxrwxrwx 1 root root 9 Dec 2 18:18 platform-pcspkr-event-spkr -> ../event4
/dev/mapper:
total 0
crw------- 1 root root 10, 236 Dec 2 18:18 control
/dev/net:
total 0
crw-rw-rw- 1 root root 10, 200 Dec 2 18:18 tun
/dev/pts:
total 0
c--------- 1 root root 5, 2 Dec 2 18:18 ptmx
/dev/raw:
total 0
crw------- 1 root root 162, 0 Dec 2 18:18 rawctl
/dev/snd:
total 0
drwxr-xr-x 2 root root 60 Dec 2 18:18 by-path
crw-rw---- 1 root audio 116, 2 Dec 2 18:18 controlC0
crw-rw---- 1 root audio 116, 3 Dec 2 18:18 pcmC0D0p
crw-rw---- 1 root audio 116, 1 Dec 2 18:18 seq
crw-rw---- 1 root audio 116, 33 Dec 2 18:18 timer
/dev/snd/by-path:
total 0
lrwxrwxrwx 1 root root 12 Dec 2 18:18 platform-pcspkr -> ../controlC0
/dev/vfio:
total 0
crw------- 1 root root 10, 196 Dec 2 18:18 vfio
/dev/virtio-ports:
total 0
lrwxrwxrwx 1 root root 11 Dec 2 18:18 org.libguestfs.channel.0 -> ../vport2p1
+ cat /proc/mounts
/dev/root / ext2 rw,noatime,block_validity,barrier,user_xattr,acl 0 0
/proc /proc proc rw,relatime 0 0
/sys /sys sysfs rw,relatime 0 0
tmpfs /run tmpfs rw,nosuid,relatime,size=97040k,mode=755 0 0
/dev /dev devtmpfs rw,relatime,size=240340k,nr_inodes=60085,mode=755 0 0
/dev/pts /dev/pts devpts rw,relatime,mode=600,ptmxmode=000 0 0
+ lvm pvs
WARNING: Failed to connect to lvmetad. Falling back to device scanning.
+ lvm vgs
WARNING: Failed to connect to lvmetad. Falling back to device scanning.
+ lvm lvs
WARNING: Failed to connect to lvmetad. Falling back to device scanning.
+ ip a
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default
qlen 1000
link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
inet 127.0.0.1/8 brd 127.255.255.255 scope host lo
valid_lft forever preferred_lft forever
inet6 ::1/128 scope host
valid_lft forever preferred_lft forever
+ ip r
+ lsmod
Module Size Used by
snd_pcsp 20480 0
snd_pcm 98304 1 snd_pcsp
joydev 20480 0
serio_raw 16384 0
ppdev 20480 0
parport_pc 28672 0
i2c_piix4 24576 0
snd_timer 32768 1 snd_pcm
snd 81920 3 snd_pcsp,snd_timer,snd_pcm
soundcore 16384 1 snd
parport 49152 2 parport_pc,ppdev
ghash_clmulni_intel 16384 0
qemu_fw_cfg 16384 0
ata_generic 16384 0
pata_acpi 16384 0
libcrc32c 16384 0
crc8 16384 0
crc7 16384 0
crc4 16384 0
crc_itu_t 16384 0
virtio_pci 24576 0
virtio_mmio 16384 0
virtio_input 16384 0
virtio_balloon 20480 0
virtio_scsi 20480 1
nd_pmem 16384 0
nd_btt 24576 1 nd_pmem
virtio_net 40960 0
virtio_crypto 20480 0
crypto_engine 16384 1 virtio_crypto
virtio_console 28672 0
virtio_rng 16384 0
virtio_blk 20480 0
virtio_ring 24576 10
virtio_input,virtio_blk,virtio_net,virtio_rng,virtio_mmio,virtio_scsi,virtio_balloon,virtio_crypto,virtio_console,virtio_pci
virtio 16384 10
virtio_input,virtio_blk,virtio_net,virtio_rng,virtio_mmio,virtio_scsi,virtio_balloon,virtio_crypto,virtio_console,virtio_pci
nfit 53248 0
crc32_generic 16384 0
crct10dif_pclmul 16384 0
crc32c_intel 24576 1
crc32_pclmul 16384 0
+ date
Sat Dec 2 18:18:13 UTC 2017
+ echo -n 'clocksource: '
clocksource: + cat /sys/devices/system/clocksource/clocksource0/current_clocksource
kvm-clock
+ echo -n 'uptime: '
uptime: + cat /proc/uptime
1.98 0.19
+ cmd=guestfsd
++ grep -Eo 'guestfs_channel=[^[:space:]]+' /proc/cmdline
+ eval
+ test x '!=' x
+ test 1 = 1
+ cmd='guestfsd --verbose'
+ test '' = 1
+ test '' = 1
+ echo guestfsd --verbose
guestfsd --verbose
+ guestfsd --verbose
lvm config:
lvmetad
trying to open virtio-serial channel
'/dev/virtio-ports/org.libguestfs.channel.0'
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle
calling: settle
libguestfs: recv_from_daemon: received GUESTFS_LAUNCH_FLAG
libguestfs: appliance is up
libguestfs: trace: launch = 0
libguestfs: trace: pread_device "/dev/sda" 4096 0
guestfsd: main_loop: new request, len 0x40
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sda
calling: settle
nbdkit: nbd.8: debug: acquire unload prevention lock
nbdkit: nbd.8: debug: pread count=4096 offset=0
nbdkit: nbd.8: debug: sending request with type 0 and cookie 0x900000008
nbdkit: nbd.8: debug: acquire unload prevention lock
nbdkit: nbd.8: debug: pread count=4096 offset=0
nbdkit: nbd.8: debug: sending request with type 0 and cookie 0x900000008
nbdkit: file.9: debug: acquire unload prevention lock
nbdkit: file.9: debug: pread count=4096 offset=0
nbdkit: file.9: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.8: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.8: debug: release unload prevention lock
libguestfs: trace: pread_device =
"\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08\x01\x02\x03\x04\x05\x06\x07\x08"<truncated,
original size 4096 bytes>
libguestfs: trace: close
libguestfs: closing guestfs handle 0x24282d0 (state 2)
libguestfs: trace: internal_autosync
guestfsd: main_loop: proc 276 (pread_device) took 0.00 seconds
guestfsd: main_loop: new request, len 0x28
umount-all: /proc/mounts: fsname=/dev/root dir=/ type=ext2
opts=rw,noatime,block_validity,barrier,user_xattr,acl freq=0 passno=0
umount-all: /proc/mounts: fsname=/proc dir=/proc type=proc opts=rw,relatime freq=0
passno=0
umount-all: /proc/mounts: fsname=/sys dir=/sys type=sysfs opts=rw,relatime freq=0
passno=0
umount-all: /proc/mounts: fsname=tmpfs dir=/run type=tmpfs
opts=rw,nosuid,relatime,size=97040k,mode=755 freq=0 passno=0
umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs
opts=rw,relatime,size=240340k,nr_inodes=60085,mode=755 freq=0 passno=0
umount-all: /proc/mounts: fsname=/dev/pts dir=/dev/pts type=devpts
opts=rw,relatime,mode=600,ptmxmode=000 freq=0 passno=0
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sdb
calling: settle
commandrvf: stdout=n stderr=y flags=0x0
commandrvf: udevadm --debug settle -E /dev/sda
calling: settle
nbdkit: nbd.9: debug: acquire unload prevention lock
nbdkit: nbd.9: debug: flush
fsync /dev/sda
nbdkit: nbd.9: debug: sending request with type 3 and cookie 0x900000008
nbdkit: nbd.9: debug: acquire unload prevention lock
nbdkit: nbd.9: debug: flush
nbdkit: nbd.9: debug: sending request with type 3 and cookie 0x900000008
nbdkit: file.10: debug: acquire unload prevention lock
nbdkit: file.10: debug: flush
nbdkit: file.10: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.9: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.9: debug: release unload prevention lock
libguestfs: trace: internal_autosync = 0
libguestfs: sending SIGTERM to process 18183
nbdkit: nbd.10: debug: acquire unload prevention lock
nbdkit: nbd.10: debug: flush
nbdkit: nbd.10: debug: sending request with type 3 and cookie 0x900000008
nbdkit: nbd.10: debug: acquire unload prevention lock
nbdkit: nbd.10: debug: flush
nbdkit: nbd.10: debug: sending request with type 3 and cookie 0x900000008
nbdkit: file.11: debug: acquire unload prevention lock
nbdkit: file.11: debug: flush
nbdkit: file.11: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.10: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.10: debug: release unload prevention lock
nbdkit: nbd.11: debug: acquire unload prevention lock
nbdkit: nbd.11: debug: flush
nbdkit: nbd.11: debug: sending request with type 3 and cookie 0x900000008
nbdkit: nbd.11: debug: acquire unload prevention lock
nbdkit: nbd.11: debug: flush
nbdkit: nbd.11: debug: sending request with type 3 and cookie 0x900000008
nbdkit: file.0: debug: acquire unload prevention lock
nbdkit: file.0: debug: flush
nbdkit: file.0: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.11: debug: release unload prevention lock
nbdkit: debug: received reply for cookie 0x900000008
nbdkit: nbd.11: debug: release unload prevention lock
nbdkit: nbd.12: debug: client sent disconnect command, closing connection
nbdkit: nbd.12: debug: exiting worker thread nbd.12
nbdkit: nbd.13: debug: client closed input socket, closing connection
nbdkit: nbd.13: debug: exiting worker thread nbd.13
nbdkit: nbd.14: debug: client closed input socket, closing connection
nbdkit: nbd.14: debug: exiting worker thread nbd.14
nbdkit: nbd.15: debug: client closed input socket, closing connection
nbdkit: nbd.15: debug: exiting worker thread nbd.15
nbdkit: nbd.1: debug: client closed input socket, closing connection
nbdkit: nbd.1: debug: exiting worker thread nbd.1
nbdkit: nbd.2: debug: client closed input socket, closing connection
nbdkit: nbd.2: debug: exiting worker thread nbd.2
nbdkit: nbd.3: debug: client closed input socket, closing connection
nbdkit: nbd.3: debug: exiting worker thread nbd.3
nbdkit: nbd.4: debug: client closed input socket, closing connection
nbdkit: nbd.4: debug: exiting worker thread nbd.4
nbdkit: nbd.0: debug: client closed input socket, closing connection
nbdkit: nbd.0: debug: exiting worker thread nbd.0
nbdkit: nbd.5: debug: client closed input socket, closing connection
nbdkit: nbd.5: debug: exiting worker thread nbd.5
nbdkit: nbd.6: debug: client closed input socket, closing connection
nbdkit: nbd.6: debug: exiting worker thread nbd.6
nbdkit: nbd.7: debug: client closed input socket, closing connection
nbdkit: nbd.7: debug: exiting worker thread nbd.7
nbdkit: nbd.8: debug: client closed input socket, closing connection
nbdkit: nbd.8: debug: exiting worker thread nbd.8
nbdkit: nbd.9: debug: client closed input socket, closing connection
nbdkit: nbd.9: debug: exiting worker thread nbd.9
nbdkit: nbd.10: debug: client closed input socket, closing connection
nbdkit: nbd.10: debug: exiting worker thread nbd.10
nbdkit: nbd.11: debug: client closed input socket, closing connection
nbdkit: nbd.11: debug: exiting worker thread nbd.11
libguestfs: qemu maxrss 166484K
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /tmp/libguestfsYp1w4f
libguestfs: command: run: rm
libguestfs: command: run: \ -rf /run/user/1000/libguestfsUEHqc9
nbdkit: nbd[1]: debug: connection cleanup with final status 0
nbdkit: nbd[1]: debug: close
nbdkit: nbd[1]: debug: sending request with type 2 and cookie 0
nbdkit: nbd.12: debug: client sent disconnect command, closing connection
nbdkit: nbd.12: debug: exiting worker thread nbd.12
nbdkit: debug: /home/rjones/d/nbdkit/plugins/nbd/.libs/nbdkit-nbd-plugin.so: unload
==18105==
nbdkit: ==18105== HEAP SUMMARY:
==18105== in use at exit: 2,473 bytes in 16 blocks
==18105== total heap usage: 2,298 allocs, 2,282 frees, 307,606 bytes allocated
==18105==
nbd.13: debug: client closed input socket, closing connection
nbdkit: nbd.13: debug: exiting worker thread nbd.13
nbdkit: nbd.14: debug: client closed input socket, closing connection
nbdkit: nbd.14: debug: exiting worker thread nbd.14
nbdkit: nbd.15: debug: client closed input socket, closing connection
nbdkit: nbd.15: debug: exiting worker thread nbd.15
nbdkit: nbd.1: debug: client closed input socket, closing connection
nbdkit: nbd.1: debug: exiting worker thread nbd.1
nbdkit: nbd.2: debug: client closed input socket, closing connection
nbdkit: nbd.2: debug: exiting worker thread nbd.2
nbdkit: nbd.3: debug: client closed input socket, closing connection
nbdkit: nbd.3: debug: exiting worker thread nbd.3
nbdkit: nbd.4: debug: client closed input socket, closing connection
nbdkit: nbd.4: debug: exiting worker thread nbd.4
nbdkit: nbd.0: debug: client closed input socket, closing connection
nbdkit: nbd.0: debug: exiting worker thread nbd.0
nbdkit: nbd.5: debug: client closed input socket, closing connection
nbdkit: nbd.5: debug: exiting worker thread nbd.5
nbdkit: nbd.6: debug: client closed input socket, closing connection
nbdkit: nbd.6: debug: exiting worker thread nbd.6
nbdkit: nbd.7: debug: client closed input socket, closing connection
nbdkit: nbd.7: debug: exiting worker thread nbd.7
nbdkit: nbd.8: debug: client closed input socket, closing connection
nbdkit: nbd.8: debug: exiting worker thread nbd.8
nbdkit: nbd.9: debug: client closed input socket, closing connection
nbdkit: nbd.9: debug: exiting worker thread nbd.9
nbdkit: nbd.10: debug: client closed input socket, closing connection
nbdkit: nbd.10: debug: exiting worker thread nbd.10
nbdkit: nbd.11: debug: client closed input socket, closing connection
nbdkit: nbd.11: debug: exiting worker thread nbd.11
nbdkit: nbd[1]: debug: connection cleanup with final status 0
nbdkit: nbd[1]: debug: close
nbdkit: nbd[1]: debug: sending request with type 2 and cookie 0
nbdkit: file.12: debug: client sent disconnect command, closing connection
nbdkit: file.12: debug: exiting worker thread file.12
==18105== LEAK SUMMARY:
==18105== definitely lost: 0 bytes in 0 blocks
==18105== indirectly lost: 0 bytes in 0 blocks
==18105== possibly lost: 0 bytes in 0 blocks
==18105== still reachable: 745 bytes in 10 blocks
==18105== suppressed: 1,728 bytes in 6 blocks
==18105== Reachable blocks (those to which a pointer was found) are not shown.
==18105== To see them, rerun with: --leak-check=full --show-leak-kinds=all
==18105==
==18105== For counts of detected and suppressed errors, rerun with: -v
==18105== ERROR SUMMARY: 23 errors from 2 contexts (suppressed: 3 from 3)
FAIL test-nbd (exit status: 119)
--
Richard Jones, Virtualization Group, Red Hat http://people.redhat.com/~rjones
Read my programming and virtualization blog: http://rwmj.wordpress.com
virt-p2v converts physical machines to virtual machines. Boot with a
live CD or over the network (PXE) and turn machines into KVM guests.
http://libguestfs.org/virt-v2v
3:03 p.m.
On 12/02/2017 12:21 PM, Richard W.M. Jones wrote:
On Sat, Dec 02, 2017 at 11:52:31AM -0600, Eric Blake wrote:
> When converting from a single transaction to a linked list, I
> forgot to free the storage for each member of the list.
>
> Reported-by: Richard W.M. Jones <rjones(a)redhat.com>
> Fixes: 7f5bb9bf13f041ea7702bda557d9dd668bc3423a
> Signed-off-by: Eric Blake <eblake(a)redhat.com>
> ---
>
> I'm still not sure why 'make check' passes while 'make
check-valgrind'
> fails for TESTS=test-nbd, but this at least avoids the memory leak.
>
> *fd = trans->u.fds[1];
> + free (trans);
> switch (be32toh (rep.error)) {
> case NBD_SUCCESS:
> if (trans->buf && read_full (h->fd, trans->buf,
trans->count) < 0)
Can this be right? valgrind seems to be saying that there are
double-free errors when I add this patch (see below).
Rather, use after free. The patch avoids the leak, but didn't do it
quite correctly. v2 coming up, and now I know why check-valgrind failed.
Sometimes, it's hard to see the actual error message because of
everything else that is also in the log.
==18076== Thread 3:
==18076== Invalid read of size 8
==18076== at 0x77EBB08: nbd_reply_raw (nbd.c:340)
==18076== by 0x77EBB08: nbd_reader (nbd.c:373)
==18076== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18076== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18076== Address 0x7452fc8 is 8 bytes inside a block of size 32 free'd
==18076== at 0x4C2ED18: free (vg_replace_malloc.c:530)
==18076== by 0x77EB996: nbd_reply_raw (nbd.c:337)
==18076== by 0x77EB996: nbd_reader (nbd.c:373)
==18076== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18076== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
==18076== Block was alloc'd at
==18076== at 0x4C2FA1E: calloc (vg_replace_malloc.c:711)
==18076== by 0x77EBBAF: nbd_request_full (nbd.c:264)
==18076== by 0x77EBD98: nbd_pread (nbd.c:602)
==18076== by 0x405986: handle_request (connections.c:884)
==18076== by 0x405986: recv_request_send_reply (connections.c:1061)
==18076== by 0x405AE7: connection_worker (connections.c:200)
==18076== by 0x55DC55A: start_thread (in /usr/lib64/libpthread-2.26.9000.so)
==18076== by 0x58E85AE: clone (in /usr/lib64/libc-2.26.9000.so)
But I also see you managed to get CFLAGS=-g propagated to the plugin in
this trace.
--
Eric Blake, Principal Software Engineer
Red Hat, Inc. +1-919-301-3266
Virtualization: qemu.org | libvirt.org
2547
days inactive
2547
days old
2 comments
2 participants
participants (2)
-
Eric Blake -
Richard W.M. Jones