Greetings, I am suffering of several weird errors which show randomly and make me suspect some concurrency issue. Libguestfs version is 1.28.1, linux kernel 3.16, libvirt 1.2.9 and qemu 2.1. What I'm trying to do is comparing the disk state at two different point of a guest execution. Disk snapshots are taken through libvirt in different moments (I am aware of caching issue), from such snapshots, new disks are created using the "qemu-img convert" command with backing file pointing to the disk being executed. Then, I spawn as many processes as disk images and in each process I create an guestfs instance and mount one of the disks. The operation seems successful as I'm gathering the data I am looking for but I suffer from these random failures: * RuntimeError: file receive cancelled by daemon - On r libguestfsmod.checksums_out (self._o, csumtype, directory, sumsfile) * RuntimeError: hivex_close: do_hivex_close: you must call 'hivex-open' first to initialize the hivex handle - On r = libguestfsmod.inspect_os (self._o) The random nature of the errors make me think about some concurrency problem. The disk images are mounted read only, each process uses a new guestfs instance and mount a different disk image. Only shared data is the backing file. Same code without backing image was running on a different server. I was able to compare whole disks image created from snapshots of a running one. Most probably I am doing something wrong but I can't figure out what.
Richard W.M. Jones
2015-May-27 12:21 UTC
Re: [Libguestfs] Concurrent scanning of same disk
On Wed, May 27, 2015 at 09:38:38AM +0300, NoxDaFox wrote:> * RuntimeError: file receive cancelled by daemon - On r > libguestfsmod.checksums_out (self._o, csumtype, directory, sumsfile) > * RuntimeError: hivex_close: do_hivex_close: you must call 'hivex-open' > first to initialize the hivex handle - On r = libguestfsmod.inspect_os > (self._o)This error is likely to be -EIO (it's actually a bug in libguestfs that it doesn't report these properly in the error message). However we cannot be sure unless you enable debugging and get the complete messages. http://libguestfs.org/guestfs-faq.1.html#debugging-libguestfs Rich. -- Richard Jones, Virtualization Group, Red Hat http://people.redhat.com/~rjones Read my programming and virtualization blog: http://rwmj.wordpress.com Fedora Windows cross-compiler. Compile Windows programs, test, and build Windows installers. Over 100 libraries supported. http://fedoraproject.org/wiki/MinGW
2015-05-27 15:21 GMT+03:00 Richard W.M. Jones <rjones@redhat.com>:> On Wed, May 27, 2015 at 09:38:38AM +0300, NoxDaFox wrote: > > * RuntimeError: file receive cancelled by daemon - On r > > libguestfsmod.checksums_out (self._o, csumtype, directory, sumsfile) > > * RuntimeError: hivex_close: do_hivex_close: you must call 'hivex-open' > > first to initialize the hivex handle - On r = libguestfsmod.inspect_os > > (self._o) > > This error is likely to be -EIO (it's actually a bug in libguestfs > that it doesn't report these properly in the error message). However > we cannot be sure unless you enable debugging and get the complete > messages. > > http://libguestfs.org/guestfs-faq.1.html#debugging-libguestfs > > Rich. > >I'm starting to wonder whether these errors are due to the fact that I compare snapshots of unconsistent disks. If so, is there a way to instruct guestfs to ignore corrupted files? It's a bit challenging to generate such logs as the error appears every now ant then. Here's the log related to a "RuntimeError: file receive cancelled by daemon". libguestfs: trace: set_verbose true libguestfs: trace: set_verbose = 0 libguestfs: create: flags = 0, handle = 0x2b45cc0, program = python libguestfs: trace: add_drive "/dev/shm/shepherd/results/newtask.processed/pre_sample_injection.qcow2" "readonly:true" libguestfs: creating COW overlay to protect original drive content libguestfs: trace: get_tmpdir libguestfs: trace: get_tmpdir = "/tmp" libguestfs: trace: disk_create "/tmp/libguestfssil2bk/overlay1" "qcow2" -1 "backingfile:/dev/shm/shepherd/results/newtask.processed/pre_sample_injection.qcow2" libguestfs: command: run: qemu-img libguestfs: command: run: \ create libguestfs: command: run: \ -f qcow2 libguestfs: command: run: \ -o backing_file=/dev/shm/shepherd/results/newtask.processed/pre_sample_injection.qcow2 libguestfs: command: run: \ /tmp/libguestfssil2bk/overlay1 Formatting '/tmp/libguestfssil2bk/overlay1', fmt=qcow2 size=64424509440 backing_file='/dev/shm/shepherd/results/newtask.processed/pre_sample_injection.qcow2' encryption=off cluster_size=65536 lazy_refcounts=off libguestfs: trace: disk_create = 0 libguestfs: trace: add_drive = 0 libguestfs: trace: launch libguestfs: trace: version libguestfs: trace: version = <struct guestfs_version *> libguestfs: trace: get_backend libguestfs: trace: get_backend = "direct" libguestfs: launch: program=python libguestfs: launch: version=1.28.1 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/libguestfssil2bk libguestfs: launch: umask=0022 libguestfs: launch: euid=0 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: [00010ms] begin building supermin appliance libguestfs: [00010ms] 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-0/lock libguestfs: command: run: \ --copy-kernel libguestfs: command: run: \ -f ext2 libguestfs: command: run: \ --host-cpu x86_64 libguestfs: command: run: \ /usr/lib/x86_64-linux-gnu/guestfs/supermin.d libguestfs: command: run: \ -o /var/tmp/.guestfs-0/appliance.d supermin: version: 5.1.11 supermin: package handler: debian/dpkg supermin: acquiring lock on /var/tmp/.guestfs-0/lock supermin: if-newer: output does not need rebuilding libguestfs: [00137ms] finished building supermin appliance libguestfs: [00137ms] begin testing qemu features libguestfs: command: run: /usr/bin/qemu-system-x86_64 libguestfs: command: run: \ -display none libguestfs: command: run: \ -help libguestfs: command: run: /usr/bin/qemu-system-x86_64 libguestfs: command: run: \ -display none libguestfs: command: run: \ -version libguestfs: qemu version 2.1 libguestfs: command: run: /usr/bin/qemu-system-x86_64 libguestfs: command: run: \ -display none libguestfs: command: run: \ -machine accel=kvm:tcg libguestfs: command: run: \ -device ? libguestfs: [00789ms] finished testing qemu features libguestfs: trace: get_backend_setting "gdb" libguestfs: trace: get_backend_setting = NULL (error) [00796ms] /usr/bin/qemu-system-x86_64 \ -global virtio-blk-pci.scsi=off \ -nodefconfig \ -enable-fips \ -nodefaults \ -display none \ -cpu host \ -machine accel=kvm:tcg \ -m 500 \ -no-reboot \ -rtc driftfix=slew \ -no-hpet \ -global kvm-pit.lost_tick_policy=discard \ -kernel /var/tmp/.guestfs-0/appliance.d/kernel \ -initrd /var/tmp/.guestfs-0/appliance.d/initrd \ -device virtio-scsi-pci,id=scsi \ -drive file=/tmp/libguestfssil2bk/overlay1,cache=unsafe,format=qcow2,id=hd0,if=none \ -device scsi-hd,drive=hd0 \ -drive file=/var/tmp/.guestfs-0/appliance.d/root,snapshot=on,id=appliance,cache=unsafe,if=none \ -device scsi-hd,drive=appliance \ -device virtio-serial-pci \ -serial stdio \ -device sga \ -chardev socket,path=/tmp/libguestfssil2bk/guestfsd.sock,id=channel0 \ -device virtserialport,chardev=channel0,name=org.libguestfs.channel.0 \ -append 'panic=1 console=ttyS0 udevtimeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm' Could not open option rom 'sgabios.bin': No such file or directory [ 0.000000] Initializing cgroup subsys cpuset [ 0.000000] Initializing cgroup subsys cpu [ 0.000000] Initializing cgroup subsys cpuacct [ 0.000000] Linux version 3.16.0-4-amd64 (debian-kernel@lists.debian.org) (gcc version 4.8.4 (Debian 4.8.4-1) ) #1 SMP Debian 3.16.7-ckt9-2 (2015-04-13) [ 0.000000] Command line: panic=1 console=ttyS0 udevtimeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm [ 0.000000] e820: BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable [ 0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved [ 0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000001f3dffff] usable [ 0.000000] BIOS-e820: [mem 0x000000001f3e0000-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] SMBIOS 2.8 present. [ 0.000000] Hypervisor detected: KVM [ 0.000000] AGP: No AGP bridge found [ 0.000000] e820: last_pfn = 0x1f3e0 max_arch_pfn = 0x400000000 [ 0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106 [ 0.000000] found SMP MP-table at [mem 0x000f0ec0-0x000f0ecf] mapped at [ffff8800000f0ec0] [ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff] [ 0.000000] init_memory_mapping: [mem 0x1ee00000-0x1effffff] [ 0.000000] init_memory_mapping: [mem 0x1c000000-0x1edfffff] [ 0.000000] init_memory_mapping: [mem 0x00100000-0x1bffffff] [ 0.000000] init_memory_mapping: [mem 0x1f000000-0x1f3dffff] [ 0.000000] RAMDISK: [mem 0x1f015000-0x1f3d7fff] [ 0.000000] No NUMA configuration found [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000001f3dffff] [ 0.000000] Initmem setup node 0 [mem 0x00000000-0x1f3dffff] [ 0.000000] NODE_DATA [mem 0x1f3db000-0x1f3dffff] [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000000] kvm-clock: cpu 0, msr 0:1f00d001, primary cpu clock [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x00001000-0x00ffffff] [ 0.000000] DMA32 [mem 0x01000000-0xffffffff] [ 0.000000] Normal empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x00001000-0x0009efff] [ 0.000000] node 0: [mem 0x00100000-0x1f3dffff] [ 0.000000] SFI: Simple Firmware Interface v0.81 http://simplefirmware.org [ 0.000000] Intel MultiProcessor Specification v1.4 [ 0.000000] MPTABLE: OEM ID: BOCHSCPU [ 0.000000] MPTABLE: Product ID: 0.1 [ 0.000000] MPTABLE: APIC at: 0xFEE00000 [ 0.000000] Processor #0 (Bootup-CPU) [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.000000] Processors: 1 [ 0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs [ 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] setup_percpu: NR_CPUS:512 nr_cpumask_bits:512 nr_cpu_ids:1 nr_node_ids:1 [ 0.000000] PERCPU: Embedded 27 pages/cpu @ffff88001ec00000 s80896 r8192 d21504 u2097152 [ 0.000000] KVM setup async PF for cpu 0 [ 0.000000] kvm-stealtime: cpu 0, msr 1ec0d080 [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 126099 [ 0.000000] Policy zone: DMA32 [ 0.000000] Kernel command line: panic=1 console=ttyS0 udevtimeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm [ 0.000000] Disabling memory control group subsystem [ 0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes) [ 0.000000] xsave: enabled xstate_bv 0x3, cntxt size 0x240 [ 0.000000] AGP: Checking aperture... [ 0.000000] AGP: No AGP bridge found [ 0.000000] Memory: 487724K/511480K available (5207K kernel code, 946K rwdata, 1832K rodata, 1204K init, 840K bss, 23756K reserved) [ 0.000000] Hierarchical RCU implementation. [ 0.000000] \tRCU dyntick-idle grace-period acceleration is enabled. [ 0.000000] \tRCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=1. [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1 [ 0.000000] NR_IRQS:33024 nr_irqs:256 16 [ 0.000000] Console: colour *CGA 80x25 [ 0.000000] console [ttyS0] enabled [ 0.000000] tsc: Detected 2659.998 MHz processor [ 0.008000] Calibrating delay loop (skipped) preset value.. 5319.99 BogoMIPS (lpj=10639992) [ 0.008000] pid_max: default: 32768 minimum: 301 [ 0.008000] Security Framework initialized [ 0.008000] AppArmor: AppArmor disabled by boot time parameter [ 0.008000] Yama: disabled by default; enable with sysctl kernel.yama.* [ 0.008067] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes) [ 0.009307] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes) [ 0.010357] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.012007] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes) [ 0.013331] Initializing cgroup subsys memory [ 0.013923] Initializing cgroup subsys devices [ 0.014539] Initializing cgroup subsys freezer [ 0.015142] Initializing cgroup subsys net_cls [ 0.015757] Initializing cgroup subsys blkio [ 0.016009] Initializing cgroup subsys perf_event [ 0.016642] Initializing cgroup subsys net_prio [ 0.017370] mce: CPU supports 10 MCE banks [ 0.017983] Last level iTLB entries: 4KB 128, 2MB 4, 4MB 4 [ 0.017983] Last level dTLB entries: 4KB 256, 2MB 0, 4MB 32, 1GB 0 [ 0.017983] tlb_flushall_shift: -1 [ 0.092630] Freeing SMP alternatives memory: 20K (ffffffff81a1b000 - ffffffff81a20000) [ 0.098843] ftrace: allocating 21614 entries in 85 pages [ 0.104465] Enabling x2apic [ 0.104874] Enabled x2apic [ 0.108004] Switched APIC routing to physical x2apic. [ 0.110328] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.111118] smpboot: CPU0: Intel(R) Xeon(R) CPU E5430 @ 2.66GHz (fam: 06, model: 17, stepping: 0a) [ 0.220080] Performance Events: 4-deep LBR, Core2 events, Intel PMU driver. [ 0.221080] ... version: 2 [ 0.221605] ... bit width: 40 [ 0.222140] ... generic registers: 2 [ 0.222666] ... value mask: 000000ffffffffff [ 0.223372] ... max period: 000000007fffffff [ 0.224001] ... fixed-purpose events: 3 [ 0.224541] ... event mask: 0000000700000003 [ 0.227712] x86: Booted up 1 node, 1 CPUs [ 0.228004] smpboot: Total of 1 processors activated (5319.99 BogoMIPS) [ 0.229286] NMI watchdog: enabled on all CPUs, permanently consumes one hw-PMU counter. [ 0.230494] devtmpfs: initialized [ 0.235569] pinctrl core: initialized pinctrl subsystem [ 0.236084] NET: Registered protocol family 16 [ 0.236820] cpuidle: using governor ladder [ 0.237367] cpuidle: using governor menu [ 0.238178] PCI: Using configuration type 1 for base access [ 0.240040] ACPI: Interpreter disabled. [ 0.240645] vgaarb: loaded [ 0.241064] PCI: Probing PCI hardware [ 0.241593] PCI host bridge to bus 0000:00 [ 0.242141] pci_bus 0000:00: root bus resource [io 0x0000-0xffff] [ 0.242960] pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff] [ 0.243900] pci_bus 0000:00: No busn resource found for root bus, will use [bus 00-ff] [ 0.250608] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] [ 0.251541] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] [ 0.252002] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] [ 0.252935] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] [ 0.254324] pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI [ 0.255280] pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB [ 0.274921] pci 0000:00:01.0: PIIX/ICH IRQ router [8086:7000] [ 0.276009] Switched to clocksource kvm-clock [ 0.283015] pnp: PnP ACPI: disabled [ 0.285237] NET: Registered protocol family 2 [ 0.286072] TCP established hash table entries: 4096 (order: 3, 32768 bytes) [ 0.287062] TCP bind hash table entries: 4096 (order: 4, 65536 bytes) [ 0.287982] TCP: Hash tables configured (established 4096 bind 4096) [ 0.288885] TCP: reno registered [ 0.289335] UDP hash table entries: 256 (order: 1, 8192 bytes) [ 0.290120] UDP-Lite hash table entries: 256 (order: 1, 8192 bytes) [ 0.290995] NET: Registered protocol family 1 [ 0.291603] pci 0000:00:00.0: Limiting direct PCI/PCI transfers [ 0.292441] pci 0000:00:01.0: PIIX3: Enabling Passive Release [ 0.293226] pci 0000:00:01.0: Activating ISA DMA hang workarounds [ 0.294157] Unpacking initramfs... [ 0.300013] Freeing initrd memory: 3852K (ffff88001f015000 - ffff88001f3d8000) [ 0.301284] platform rtc_cmos: registered platform RTC device (no PNP device found) [ 0.302421] microcode: CPU0 sig=0x1067a, pf=0x1, revision=0x1 [ 0.303259] microcode: Microcode Update Driver: v2.00 < tigran@aivazian.fsnet.co.uk>, Peter Oruba [ 0.304731] futex hash table entries: 256 (order: 2, 16384 bytes) [ 0.305613] audit: initializing netlink subsys (disabled) [ 0.306376] audit: type=2000 audit(1432794962.390:1): initialized [ 0.307552] HugeTLB registered 2 MB page size, pre-allocated 0 pages [ 0.308452] zbud: loaded [ 0.308919] VFS: Disk quotas dquot_6.5.2 [ 0.309469] Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.310389] msgmni has been set to 960 [ 0.311205] alg: No test for stdrng (krng) [ 0.311785] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) [ 0.312848] io scheduler noop registered [ 0.313375] io scheduler deadline registered [ 0.313986] io scheduler cfq registered (default) [ 0.314682] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 [ 0.315453] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 [ 0.316488] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.344276] serial8250: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 0.345605] Linux agpgart interface v0.103 [ 0.346264] i8042: PNP: No PS/2 controller found. Probing ports directly. [ 0.347960] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 0.348648] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 0.349445] mousedev: PS/2 mouse device common for all mice [ 0.350484] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 [ 0.352087] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0 [ 0.353069] rtc_cmos rtc_cmos: alarms up to one day, 114 bytes nvram [ 0.353917] ledtrig-cpu: registered to indicate activity on CPUs [ 0.354769] AMD IOMMUv2 driver by Joerg Roedel <joerg.roedel@amd.com> [ 0.355633] AMD IOMMUv2 functionality not available on this system [ 0.356560] TCP: cubic registered [ 0.357022] NET: Registered protocol family 10 [ 0.358515] mip6: Mobile IPv6 [ 0.358927] NET: Registered protocol family 17 [ 0.359539] mpls_gso: MPLS GSO support [ 0.360282] registered taskstats version 1 [ 0.361148] rtc_cmos rtc_cmos: setting system clock to 2015-05-28 06:36:02 UTC (1432794962) [ 0.364149] Freeing unused kernel memory: 1204K (ffffffff818ee000 - ffffffff81a1b000) [ 0.365218] Write protecting the kernel read-only data: 8192k [ 0.368551] Freeing unused kernel memory: 924K (ffff880001519000 - ffff880001600000) [ 0.370157] Freeing unused kernel memory: 216K (ffff8800017ca000 - ffff880001800000) supermin: mounting /proc supermin: uptime: 0.37 0.10 supermin: ext2 mini initrd starting up: 5.1.11 zlib xz supermin: cmdline: panic=1 console=ttyS0 udevtimeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm supermin: mounting /sys supermin: internal insmod crc32-pclmul.ko [ 0.375955] PCLMULQDQ-NI instructions are not detected. insmod: init_module: crc32-pclmul.ko: No such device supermin: internal insmod crc32c-intel.ko insmod: init_module: crc32c-intel.ko: No such device supermin: internal insmod crct10dif_common.ko supermin: internal insmod crct10dif-pclmul.ko insmod: init_module: crct10dif-pclmul.ko: No such device supermin: internal insmod crc32.ko [ 0.381649] alg: No test for crc32 (crc32-table) supermin: internal insmod crc32c_generic.ko supermin: internal insmod crct10dif_generic.ko supermin: internal insmod scsi_mod.ko [ 0.388659] SCSI subsystem initialized supermin: internal insmod libata.ko supermin: internal insmod ata_piix.ko [ 0.394730] scsi0 : ata_piix [ 0.395216] scsi1 : ata_piix [ 0.395656] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc060 irq 14 [ 0.396589] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc068 irq 15 supermin: internal insmod virtio.ko supermin: internal insmod virtio_ring.ko supermin: internal insmod virtio_blk.ko supermin: internal insmod rng-core.ko supermin: internal insmod virtio-rng.ko supermin: internal insmod virtio_console.ko supermin: internal insmod ideapad_slidebar.ko [ 0.559686] ideapad_slidebar: DMI does not match insmod: init_module: ideapad_slidebar.ko: No such device supermin: internal insmod virtio_net.ko supermin: internal insmod sparse-keymap.ko supermin: internal insmod rfkill.ko supermin: internal insmod ideapad-laptop.ko supermin: internal insmod megaraid.ko supermin: internal insmod megaraid_mm.ko [ 0.567734] megaraid cmm: 2.20.2.7 (Release Date: Sun Jul 16 00:01:03 EST 2006) supermin: internal insmod megaraid_mbox.ko [ 0.569931] megaraid: 2.20.5.1 (Release Date: Thu Nov 16 15:32:35 EST 2006) supermin: internal insmod megaraid_sas.ko [ 0.572770] megasas: 06.803.01.00-rc1 Mon. Mar. 10 17:00:00 PDT 2014 supermin: internal insmod scsi_transport_spi.ko supermin: internal insmod crc-t10dif.ko supermin: internal insmod sd_mod.ko supermin: internal insmod cdrom.ko supermin: internal insmod sr_mod.ko supermin: internal insmod sym53c8xx.ko supermin: internal insmod virtio_scsi.ko supermin: internal insmod virtio_balloon.ko supermin: internal insmod virtio_pci.ko [ 0.585246] virtio-pci 0000:00:02.0: PCI->APIC IRQ transform: INT A -> IRQ 34 [ 0.587065] scsi2 : Virtio SCSI HBA [ 0.587910] scsi 2:0:0:0: Direct-Access QEMU QEMU HARDDISK 2.1. PQ: 0 ANSI: 5 [ 0.589028] virtio-pci 0000:00:03.0: PCI->APIC IRQ transform: INT A -> IRQ 35 [ 0.592304] scsi 2:0:1:0: Direct-Access QEMU QEMU HARDDISK 2.1. PQ: 0 ANSI: 5 [ 0.611371] sd 2:0:0:0: [sda] 125829120 512-byte logical blocks: (64.4 GB/60.0 GiB) [ 0.612904] sd 2:0:1:0: [sdb] 8388608 512-byte logical blocks: (4.29 GB/4.00 GiB) [ 0.614150] sd 2:0:0:0: [sda] Write Protect is off [ 0.615007] sd 2:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 0.616656] sd 2:0:1:0: [sdb] Write Protect is off [ 0.617517] sd 2:0:1:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 0.619697] sda: sda1 sda2 [ 0.621068] sdb: unknown partition table [ 0.621733] sd 2:0:0:0: [sda] Attached SCSI disk [ 0.623098] sd 2:0:1:0: [sdb] Attached SCSI disk supermin: internal insmod jbd2.ko supermin: internal insmod mbcache.ko supermin: internal insmod crc16.ko supermin: internal insmod ext4.ko supermin: internal insmod crc-ccitt.ko supermin: internal insmod crc-itu-t.ko supermin: internal insmod crc7.ko supermin: internal insmod libcrc32c.ko supermin: picked /sys/block/sdb/dev as root device supermin: creating /dev/root as block special 8:16 supermin: mounting new root on /root [ 0.652295] EXT4-fs (sdb): mounting ext2 file system using the ext4 subsystem [ 0.657446] EXT4-fs (sdb): mounted filesystem without journal. Opts: supermin: chroot Starting /init script ... [ 1.375941] systemd-udevd[60]: starting version 215 [ 1.411520] random: systemd-udevd urandom read with 73 bits of entropy available [ 1.523391] input: PC Speaker as /devices/platform/pcspkr/input/input2 [ 1.538503] sd 2:0:0:0: Attached scsi generic sg0 type 0 [ 1.539282] sd 2:0:1:0: Attached scsi generic sg1 type 0 [/usr/lib/tmpfiles.d/systemd.conf:26] Failed to replace specifiers: /run/log/journal/%m [/usr/lib/tmpfiles.d/systemd.conf:29] Failed to replace specifiers: /var/log/journal/%m /init: 73: /init: cannot create /sys/block/{h,s,ub,v}d*/queue/scheduler: Directory nonexistent [ 1.588409] ACPI Exception: AE_BAD_PARAMETER, Thread 479847376 could not acquire Mutex [0x1] (20140424/utmutex-285) [ 1.590354] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 mdadm: No arrays found in config file or automatically[ 2.324163] random: nonblocking pool is initialized [ 2.363759] device-mapper: uevent: version 1.0.3 [ 2.367196] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 [ 2.369066] device-mapper: ioctl: 4.27.0-ioctl (2013-10-30) initialised: dm-devel@redhat.com No volume groups found /init: 100: /init: ldmtool: not found Linux (none) 3.16.0-4-amd64 #1 SMP Debian 3.16.7-ckt9-2 (2015-04-13) x86_64 GNU/Linux /dev: total 0 crw------- 1 0 0 10, 235 May 28 06:36 autofs drwxr-xr-x 2 0 0 120 May 28 06:36 block drwxr-xr-x 2 0 0 80 May 28 06:36 bsg crw------- 1 0 0 10, 234 May 28 06:36 btrfs-control drwxr-xr-x 2 0 0 2140 May 28 06:36 char crw------- 1 0 0 5, 1 May 28 06:36 console lrwxrwxrwx 1 0 0 11 May 28 06:36 core -> /proc/kcore drwxr-xr-x 2 0 0 60 May 28 06:36 cpu crw------- 1 0 0 10, 62 May 28 06:36 cpu_dma_latency crw------- 1 0 0 10, 203 May 28 06:36 cuse drwxr-xr-x 6 0 0 120 May 28 06:36 disk lrwxrwxrwx 1 0 0 13 May 28 06:36 fd -> /proc/self/fd crw-rw-rw- 1 0 0 1, 7 May 28 06:36 full crw------- 1 0 0 10, 229 May 28 06:36 fuse drwxr-xr-x 3 0 0 160 May 28 06:36 input crw-r--r-- 1 0 0 1, 11 May 28 06:36 kmsg crw------- 1 0 0 10, 237 May 28 06:36 loop-control drwxr-xr-x 2 0 0 60 May 28 06:36 mapper crw------- 1 0 0 10, 227 May 28 06:36 mcelog crw------- 1 0 0 10, 58 May 28 06:36 megadev0 crw------- 1 0 0 1, 1 May 28 06:36 mem drwxr-xr-x 2 0 0 60 May 28 06:36 net crw------- 1 0 0 10, 61 May 28 06:36 network_latency crw------- 1 0 0 10, 60 May 28 06:36 network_throughput crw-rw-rw- 1 0 0 1, 3 May 28 06:36 null crw------- 1 0 0 1, 4 May 28 06:36 port crw------- 1 0 0 108, 0 May 28 06:36 ppp crw------- 1 0 0 10, 1 May 28 06:36 psaux crw-rw-rw- 1 0 0 5, 2 May 28 06:36 ptmx crw-rw-rw- 1 0 0 1, 8 May 28 06:36 random crw------- 1 0 0 10, 59 May 28 06:36 rfkill lrwxrwxrwx 1 0 0 4 May 28 06:36 rtc -> rtc0 crw------- 1 0 0 254, 0 May 28 06:36 rtc0 brw------- 1 0 0 8, 0 May 28 06:36 sda brw------- 1 0 0 8, 1 May 28 06:36 sda1 brw------- 1 0 0 8, 2 May 28 06:36 sda2 brw------- 1 0 0 8, 16 May 28 06:36 sdb crw------- 1 0 0 21, 0 May 28 06:36 sg0 crw------- 1 0 0 21, 1 May 28 06:36 sg1 crw------- 1 0 0 10, 231 May 28 06:36 snapshot drwxr-xr-x 2 0 0 80 May 28 06:36 snd lrwxrwxrwx 1 0 0 15 May 28 06:36 stderr -> /proc/self/fd/2 lrwxrwxrwx 1 0 0 15 May 28 06:36 stdin -> /proc/self/fd/0 lrwxrwxrwx 1 0 0 15 May 28 06:36 stdout -> /proc/self/fd/1 crw-rw-rw- 1 0 0 5, 0 May 28 06:36 tty crw------- 1 0 0 4, 0 May 28 06:36 tty0 crw------- 1 0 0 4, 1 May 28 06:36 tty1 crw------- 1 0 0 4, 10 May 28 06:36 tty10 crw------- 1 0 0 4, 11 May 28 06:36 tty11 crw------- 1 0 0 4, 12 May 28 06:36 tty12 crw------- 1 0 0 4, 13 May 28 06:36 tty13 crw------- 1 0 0 4, 14 May 28 06:36 tty14 crw------- 1 0 0 4, 15 May 28 06:36 tty15 crw------- 1 0 0 4, 16 May 28 06:36 tty16 crw------- 1 0 0 4, 17 May 28 06:36 tty17 crw------- 1 0 0 4, 18 May 28 06:36 tty18 crw------- 1 0 0 4, 19 May 28 06:36 tty19 crw------- 1 0 0 4, 2 May 28 06:36 tty2 crw------- 1 0 0 4, 20 May 28 06:36 tty20 crw------- 1 0 0 4, 21 May 28 06:36 tty21 crw------- 1 0 0 4, 22 May 28 06:36 tty22 crw------- 1 0 0 4, 23 May 28 06:36 tty23 crw------- 1 0 0 4, 24 May 28 06:36 tty24 crw------- 1 0 0 4, 25 May 28 06:36 tty25 crw------- 1 0 0 4, 26 May 28 06:36 tty26 crw------- 1 0 0 4, 27 May 28 06:36 tty27 crw------- 1 0 0 4, 28 May 28 06:36 tty28 crw------- 1 0 0 4, 29 May 28 06:36 tty29 crw------- 1 0 0 4, 3 May 28 06:36 tty3 crw------- 1 0 0 4, 30 May 28 06:36 tty30 crw------- 1 0 0 4, 31 May 28 06:36 tty31 crw------- 1 0 0 4, 32 May 28 06:36 tty32 crw------- 1 0 0 4, 33 May 28 06:36 tty33 crw------- 1 0 0 4, 34 May 28 06:36 tty34 crw------- 1 0 0 4, 35 May 28 06:36 tty35 crw------- 1 0 0 4, 36 May 28 06:36 tty36 crw------- 1 0 0 4, 37 May 28 06:36 tty37 crw------- 1 0 0 4, 38 May 28 06:36 tty38 crw------- 1 0 0 4, 39 May 28 06:36 tty39 crw------- 1 0 0 4, 4 May 28 06:36 tty4 crw------- 1 0 0 4, 40 May 28 06:36 tty40 crw------- 1 0 0 4, 41 May 28 06:36 tty41 crw------- 1 0 0 4, 42 May 28 06:36 tty42 crw------- 1 0 0 4, 43 May 28 06:36 tty43 crw------- 1 0 0 4, 44 May 28 06:36 tty44 crw------- 1 0 0 4, 45 May 28 06:36 tty45 crw------- 1 0 0 4, 46 May 28 06:36 tty46 crw------- 1 0 0 4, 47 May 28 06:36 tty47 crw------- 1 0 0 4, 48 May 28 06:36 tty48 crw------- 1 0 0 4, 49 May 28 06:36 tty49 crw------- 1 0 0 4, 5 May 28 06:36 tty5 crw------- 1 0 0 4, 50 May 28 06:36 tty50 crw------- 1 0 0 4, 51 May 28 06:36 tty51 crw------- 1 0 0 4, 52 May 28 06:36 tty52 crw------- 1 0 0 4, 53 May 28 06:36 tty53 crw------- 1 0 0 4, 54 May 28 06:36 tty54 crw------- 1 0 0 4, 55 May 28 06:36 tty55 crw------- 1 0 0 4, 56 May 28 06:36 tty56 crw------- 1 0 0 4, 57 May 28 06:36 tty57 crw------- 1 0 0 4, 58 May 28 06:36 tty58 crw------- 1 0 0 4, 59 May 28 06:36 tty59 crw------- 1 0 0 4, 6 May 28 06:36 tty6 crw------- 1 0 0 4, 60 May 28 06:36 tty60 crw------- 1 0 0 4, 61 May 28 06:36 tty61 crw------- 1 0 0 4, 62 May 28 06:36 tty62 crw------- 1 0 0 4, 63 May 28 06:36 tty63 crw------- 1 0 0 4, 7 May 28 06:36 tty7 crw------- 1 0 0 4, 8 May 28 06:36 tty8 crw------- 1 0 0 4, 9 May 28 06:36 tty9 crw------- 1 0 0 4, 64 May 28 06:36 ttyS0 crw------- 1 0 0 4, 65 May 28 06:36 ttyS1 crw------- 1 0 0 4, 66 May 28 06:36 ttyS2 crw------- 1 0 0 4, 67 May 28 06:36 ttyS3 crw------- 1 0 0 10, 239 May 28 06:36 uhid crw------- 1 0 0 10, 223 May 28 06:36 uinput crw-rw-rw- 1 0 0 1, 9 May 28 06:36 urandom crw------- 1 0 0 7, 0 May 28 06:36 vcs crw------- 1 0 0 7, 1 May 28 06:36 vcs1 crw------- 1 0 0 7, 128 May 28 06:36 vcsa crw------- 1 0 0 7, 129 May 28 06:36 vcsa1 drwxr-xr-x 2 0 0 60 May 28 06:36 vfio crw------- 1 0 0 10, 63 May 28 06:36 vga_arbiter crw------- 1 0 0 10, 137 May 28 06:36 vhci crw------- 1 0 0 10, 238 May 28 06:36 vhost-net drwxr-xr-x 2 0 0 60 May 28 06:36 virtio-ports crw------- 1 0 0 249, 1 May 28 06:36 vport1p1 crw-rw-rw- 1 0 0 1, 5 May 28 06:36 zero /dev/block: total 0 lrwxrwxrwx 1 0 0 6 May 28 06:36 8:0 -> ../sda lrwxrwxrwx 1 0 0 7 May 28 06:36 8:1 -> ../sda1 lrwxrwxrwx 1 0 0 6 May 28 06:36 8:16 -> ../sdb lrwxrwxrwx 1 0 0 7 May 28 06:36 8:2 -> ../sda2 /dev/bsg: total 0 crw------- 1 0 0 252, 0 May 28 06:36 2:0:0:0 crw------- 1 0 0 252, 1 May 28 06:36 2:0:1:0 /dev/char: total 0 lrwxrwxrwx 1 0 0 8 May 28 06:36 10:1 -> ../psaux lrwxrwxrwx 1 0 0 16 May 28 06:36 10:184 -> ../cpu/microcode lrwxrwxrwx 1 0 0 9 May 28 06:36 10:227 -> ../mcelog lrwxrwxrwx 1 0 0 11 May 28 06:36 10:231 -> ../snapshot lrwxrwxrwx 1 0 0 17 May 28 06:36 10:236 -> ../mapper/control lrwxrwxrwx 1 0 0 11 May 28 06:36 10:58 -> ../megadev0 lrwxrwxrwx 1 0 0 9 May 28 06:36 10:59 -> ../rfkill lrwxrwxrwx 1 0 0 21 May 28 06:36 10:60 -> ../network_throughput lrwxrwxrwx 1 0 0 18 May 28 06:36 10:61 -> ../network_latency lrwxrwxrwx 1 0 0 18 May 28 06:36 10:62 -> ../cpu_dma_latency lrwxrwxrwx 1 0 0 14 May 28 06:36 10:63 -> ../vga_arbiter lrwxrwxrwx 1 0 0 15 May 28 06:36 13:32 -> ../input/mouse0 lrwxrwxrwx 1 0 0 13 May 28 06:36 13:63 -> ../input/mice lrwxrwxrwx 1 0 0 15 May 28 06:36 13:64 -> ../input/event0 lrwxrwxrwx 1 0 0 15 May 28 06:36 13:65 -> ../input/event1 lrwxrwxrwx 1 0 0 15 May 28 06:36 13:66 -> ../input/event2 lrwxrwxrwx 1 0 0 6 May 28 06:36 1:1 -> ../mem lrwxrwxrwx 1 0 0 7 May 28 06:36 1:11 -> ../kmsg lrwxrwxrwx 1 0 0 7 May 28 06:36 1:3 -> ../null lrwxrwxrwx 1 0 0 7 May 28 06:36 1:4 -> ../port lrwxrwxrwx 1 0 0 7 May 28 06:36 1:5 -> ../zero lrwxrwxrwx 1 0 0 7 May 28 06:36 1:7 -> ../full lrwxrwxrwx 1 0 0 9 May 28 06:36 1:8 -> ../random lrwxrwxrwx 1 0 0 10 May 28 06:36 1:9 -> ../urandom lrwxrwxrwx 1 0 0 6 May 28 06:36 21:0 -> ../sg0 lrwxrwxrwx 1 0 0 6 May 28 06:36 21:1 -> ../sg1 lrwxrwxrwx 1 0 0 11 May 28 06:36 249:1 -> ../vport1p1 lrwxrwxrwx 1 0 0 14 May 28 06:36 252:0 -> ../bsg/2:0:0:0 lrwxrwxrwx 1 0 0 14 May 28 06:36 252:1 -> ../bsg/2:0:1:0 lrwxrwxrwx 1 0 0 7 May 28 06:36 254:0 -> ../rtc0 lrwxrwxrwx 1 0 0 7 May 28 06:36 4:0 -> ../tty0 lrwxrwxrwx 1 0 0 7 May 28 06:36 4:1 -> ../tty1 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:10 -> ../tty10 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:11 -> ../tty11 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:12 -> ../tty12 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:13 -> ../tty13 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:14 -> ../tty14 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:15 -> ../tty15 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:16 -> ../tty16 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:17 -> ../tty17 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:18 -> ../tty18 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:19 -> ../tty19 lrwxrwxrwx 1 0 0 7 May 28 06:36 4:2 -> ../tty2 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:20 -> ../tty20 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:21 -> ../tty21 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:22 -> ../tty22 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:23 -> ../tty23 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:24 -> ../tty24 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:25 -> ../tty25 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:26 -> ../tty26 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:27 -> ../tty27 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:28 -> ../tty28 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:29 -> ../tty29 lrwxrwxrwx 1 0 0 7 May 28 06:36 4:3 -> ../tty3 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:30 -> ../tty30 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:31 -> ../tty31 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:32 -> ../tty32 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:33 -> ../tty33 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:34 -> ../tty34 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:35 -> ../tty35 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:36 -> ../tty36 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:37 -> ../tty37 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:38 -> ../tty38 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:39 -> ../tty39 lrwxrwxrwx 1 0 0 7 May 28 06:36 4:4 -> ../tty4 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:40 -> ../tty40 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:41 -> ../tty41 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:42 -> ../tty42 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:43 -> ../tty43 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:44 -> ../tty44 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:45 -> ../tty45 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:46 -> ../tty46 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:47 -> ../tty47 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:48 -> ../tty48 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:49 -> ../tty49 lrwxrwxrwx 1 0 0 7 May 28 06:36 4:5 -> ../tty5 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:50 -> ../tty50 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:51 -> ../tty51 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:52 -> ../tty52 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:53 -> ../tty53 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:54 -> ../tty54 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:55 -> ../tty55 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:56 -> ../tty56 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:57 -> ../tty57 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:58 -> ../tty58 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:59 -> ../tty59 lrwxrwxrwx 1 0 0 7 May 28 06:36 4:6 -> ../tty6 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:60 -> ../tty60 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:61 -> ../tty61 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:62 -> ../tty62 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:63 -> ../tty63 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:64 -> ../ttyS0 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:65 -> ../ttyS1 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:66 -> ../ttyS2 lrwxrwxrwx 1 0 0 8 May 28 06:36 4:67 -> ../ttyS3 lrwxrwxrwx 1 0 0 7 May 28 06:36 4:7 -> ../tty7 lrwxrwxrwx 1 0 0 7 May 28 06:36 4:8 -> ../tty8 lrwxrwxrwx 1 0 0 7 May 28 06:36 4:9 -> ../tty9 lrwxrwxrwx 1 0 0 6 May 28 06:36 5:0 -> ../tty lrwxrwxrwx 1 0 0 10 May 28 06:36 5:1 -> ../console lrwxrwxrwx 1 0 0 7 May 28 06:36 5:2 -> ../ptmx lrwxrwxrwx 1 0 0 6 May 28 06:36 7:0 -> ../vcs lrwxrwxrwx 1 0 0 7 May 28 06:36 7:1 -> ../vcs1 lrwxrwxrwx 1 0 0 7 May 28 06:36 7:128 -> ../vcsa lrwxrwxrwx 1 0 0 8 May 28 06:36 7:129 -> ../vcsa1 /dev/cpu: total 0 crw------- 1 0 0 10, 184 May 28 06:36 microcode /dev/disk: total 0 drwxr-xr-x 2 0 0 120 May 28 06:36 by-id drwxr-xr-x 2 0 0 60 May 28 06:36 by-label drwxr-xr-x 2 0 0 120 May 28 06:36 by-path drwxr-xr-x 2 0 0 100 May 28 06:36 by-uuid /dev/disk/by-id: total 0 lrwxrwxrwx 1 0 0 9 May 28 06:36 scsi-0QEMU_QEMU_HARDDISK_appliance -> ../../sdb lrwxrwxrwx 1 0 0 9 May 28 06:36 scsi-0QEMU_QEMU_HARDDISK_hd0 -> ../../sda lrwxrwxrwx 1 0 0 10 May 28 06:36 scsi-0QEMU_QEMU_HARDDISK_hd0-part1 -> ../../sda1 lrwxrwxrwx 1 0 0 10 May 28 06:36 scsi-0QEMU_QEMU_HARDDISK_hd0-part2 -> ../../sda2 /dev/disk/by-label: total 0 lrwxrwxrwx 1 0 0 10 May 28 06:36 System\x20Reserved -> ../../sda1 /dev/disk/by-path: total 0 lrwxrwxrwx 1 0 0 9 May 28 06:36 pci-0000:00:02.0-scsi-0:0:0:0 -> ../../sda lrwxrwxrwx 1 0 0 10 May 28 06:36 pci-0000:00:02.0-scsi-0:0:0:0-part1 -> ../../sda1 lrwxrwxrwx 1 0 0 10 May 28 06:36 pci-0000:00:02.0-scsi-0:0:0:0-part2 -> ../../sda2 lrwxrwxrwx 1 0 0 9 May 28 06:36 pci-0000:00:02.0-scsi-0:0:1:0 -> ../../sdb /dev/disk/by-uuid: total 0 lrwxrwxrwx 1 0 0 10 May 28 06:36 2EC85F8BC85F4FE7 -> ../../sda1 lrwxrwxrwx 1 0 0 9 May 28 06:36 885850b1-ca45-49f7-af27-f17bc946c164 -> ../../sdb lrwxrwxrwx 1 0 0 10 May 28 06:36 DE8C61948C61684B -> ../../sda2 /dev/input: total 0 drwxr-xr-x 2 0 0 120 May 28 06:36 by-path crw------- 1 0 0 13, 64 May 28 06:36 event0 crw------- 1 0 0 13, 65 May 28 06:36 event1 crw------- 1 0 0 13, 66 May 28 06:36 event2 crw------- 1 0 0 13, 63 May 28 06:36 mice crw------- 1 0 0 13, 32 May 28 06:36 mouse0 /dev/input/by-path: total 0 lrwxrwxrwx 1 0 0 9 May 28 06:36 platform-i8042-serio-0-event-kbd -> ../event0 lrwxrwxrwx 1 0 0 9 May 28 06:36 platform-i8042-serio-1-event-mouse -> ../event2 lrwxrwxrwx 1 0 0 9 May 28 06:36 platform-i8042-serio-1-mouse -> ../mouse0 lrwxrwxrwx 1 0 0 9 May 28 06:36 platform-pcspkr-event-spkr -> ../event1 /dev/mapper: total 0 crw------- 1 0 0 10, 236 May 28 06:36 control /dev/net: total 0 crw------- 1 0 0 10, 200 May 28 06:36 tun /dev/snd: total 0 crw------- 1 0 0 116, 1 May 28 06:36 seq crw------- 1 0 0 116, 33 May 28 06:36 timer /dev/vfio: total 0 crw------- 1 0 0 10, 196 May 28 06:36 vfio /dev/virtio-ports: total 0 lrwxrwxrwx 1 0 0 11 May 28 06:36 org.libguestfs.channel.0 -> ../vport1p1 rootfs / rootfs rw 0 0 proc /proc proc rw,relatime 0 0 /dev/root / ext2 rw,noatime 0 0 /proc /proc proc rw,relatime 0 0 /sys /sys sysfs rw,relatime 0 0 tmpfs /run tmpfs rw,nosuid,relatime,size=98788k,mode=755 0 0 /dev /dev devtmpfs rw,relatime,size=243872k,nr_inodes=60968,mode=755 0 0 No volume groups found No volume groups found 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default 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 Module Size Used by dm_mod 89373 0 evdev 17445 0 i2c_piix4 20864 0 i2c_core 46012 1 i2c_piix4 ata_generic 12490 0 sg 29973 0 psmouse 99143 0 serio_raw 12849 0 pcspkr 12595 0 libcrc32c 12426 0 crc7 12345 0 crc_itu_t 12347 0 crc_ccitt 12347 0 ext4 473802 1 crc16 12343 1 ext4 mbcache 17171 1 ext4 jbd2 82413 1 ext4 virtio_pci 17389 0 virtio_balloon 13047 0 virtio_scsi 17816 1 sym53c8xx 74168 0 sr_mod 21903 0 cdrom 47424 1 sr_mod sd_mod 44356 1 crc_t10dif 12431 1 sd_mod scsi_transport_spi 27851 1 sym53c8xx megaraid_sas 97499 0 megaraid_mbox 38485 0 megaraid_mm 17240 1 megaraid_mbox megaraid 42836 0 ideapad_laptop 17447 0 rfkill 18867 1 ideapad_laptop sparse_keymap 12818 1 ideapad_laptop virtio_net 26553 0 virtio_console 22655 0 virtio_rng 12783 0 rng_core 12733 1 virtio_rng virtio_blk 17345 0 virtio_ring 17513 7 virtio_blk,virtio_net,virtio_pci,virtio_rng,virtio_balloon,virtio_console,virtio_scsi virtio 13058 7 virtio_blk,virtio_net,virtio_pci,virtio_rng,virtio_balloon,virtio_console,virtio_scsi ata_piix 33592 0 libata 177457 2 ata_generic,ata_piix scsi_mod 191405 10 sg,megaraid_mbox,scsi_transport_spi,libata,sym53c8xx,sd_mod,sr_mod,megaraid,megaraid_sas,virtio_scsi crct10dif_generic 12581 1 crc32c_generic 12656 1 crc32 12648 0 crct10dif_common 12356 2 crct10dif_generic,crc_t10dif Thu May 28 06:36:04 UTC 2015 clocksource: kvm-clock uptime: 2.69 0.78 verbose daemon enabled linux commmand line: panic=1 console=ttyS0 udevtimeout=6000 no_timer_check acpi=off printk.time=1 cgroup_disable=memory root=/dev/sdb selinux=0 guestfs_verbose=1 TERM=xterm trying to open virtio-serial channel '/dev/virtio-ports/org.libguestfs.channel.0' udevadm --debug settle calling: settle libguestfs: recv_from_daemon: received GUESTFS_LAUNCH_FLAG libguestfs: [05639ms] appliance is up libguestfs: trace: launch = 0 libguestfs: trace: inspect_os libguestfs: trace: umount_all guestfsd: main_loop: new request, len 0x28 umount-all: /proc/mounts: fsname=rootfs dir=/ type=rootfs opts=rw 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=/dev/root dir=/ type=ext2 opts=rw,noatime 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=98788k,mode=755 freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=243872k,nr_inodes=60968,mode=755 freq=0 passno=0 guestfsd: main_loop: proc 47 (umount_all) took 0.00 seconds libguestfs: trace: umount_all = 0 libguestfs: trace: list_filesystems libguestfs: trace: feature_available "lvm2" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 398 (feature_available) took 0.00 seconds libguestfs: trace: feature_available = 1 libguestfs: trace: feature_available "ldm" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 398 (feature_available) took 0.00 seconds libguestfs: trace: feature_available = 0 libguestfs: trace: list_devices guestfsd: main_loop: new request, len 0x28 guestfsd: main_loop: proc 7 (list_devices) took 0.00 seconds libguestfs: trace: list_devices = ["/dev/sda"] libguestfs: trace: list_partitions guestfsd: main_loop: new request, len 0x28 guestfsd: main_loop: proc 8 (list_partitions) took 0.00 seconds libguestfs: trace: list_partitions = ["/dev/sda1", "/dev/sda2"] libguestfs: trace: list_md_devices guestfsd: main_loop: new request, len 0x28 guestfsd: main_loop: proc 300 (list_md_devices) took 0.00 seconds libguestfs: trace: list_md_devices = [] libguestfs: trace: part_to_dev "/dev/sda1" guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 272 (part_to_dev) took 0.00 seconds libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: part_to_dev "/dev/sda2" guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 272 (part_to_dev) took 0.00 seconds libguestfs: trace: part_to_dev = "/dev/sda" libguestfs: trace: vfs_type "/dev/sda1" guestfsd: main_loop: new request, len 0x38 blkid -c /dev/null -o value -s TYPE /dev/sda1 libguestfs: trace: vfs_type = "ntfs" libguestfs: trace: vfs_type "/dev/sda2" guestfsd: main_loop: proc 198 (vfs_type) took 0.02 seconds guestfsd: main_loop: new request, len 0x38 blkid -c /dev/null -o value -s TYPE /dev/sda2 libguestfs: trace: vfs_type = "ntfs" libguestfs: trace: lvs guestfsd: main_loop: proc 198 (vfs_type) took 0.01 seconds guestfsd: main_loop: new request, len 0x28 lvm lvs -o vg_name,lv_name --noheadings --separator / No volume groups found libguestfs: trace: lvs = [] libguestfs: trace: list_filesystems = ["/dev/sda1", "ntfs", "/dev/sda2", "ntfs"] libguestfs: trace: vfs_type "/dev/sda1" guestfsd: main_loop: proc 11 (lvs) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 blkid -c /dev/null -o value -s TYPE /dev/sda1 libguestfs: trace: vfs_type = "ntfs" libguestfs: check_for_filesystem_on: /dev/sda1 (ntfs) libguestfs: trace: internal_parse_mountable "/dev/sda1" guestfsd: main_loop: proc 198 (vfs_type) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 396 (internal_parse_mountable) took 0.00 seconds libguestfs: trace: internal_parse_mountable = <struct guestfs_internal_mountable *> libguestfs: trace: is_whole_device "/dev/sda1" guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 395 (is_whole_device) tolibguestfs: trace: is_whole_device = 0 libguestfs: trace: mount_ro "/dev/sda1" "/" ok 0.00 seconds guestfsd: main_loop: new request, len 0x40 mount -o ro /dev/sda1 /sysroot/ [ 2.938232] fuse init (API version 7.23) libguestfs: trace: mount_ro = 0 libguestfs: trace: part_to_partnum "/dev/sda1" guestfsd: main_loop: proc 73 (mount_ro) took 0.19 seconds guestfsd: main_loop: new request, len 0x38 libguestfs: trace: part_to_partnum = 1 libguestfs: trace: is_dir "/etc" guestfsd: main_loop: proc 293 (part_to_partnum) took 0.00 seconds guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: is_dir "/bin" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: is_dir "/share" guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 38 (is_dir) took 0.00 secondslibguestfs: trace: is_dir = 0 libguestfs: trace: is_file "/grub/menu.lst" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/grub/grub.conf" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/grub2/grub.cfg" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/hurd/console" seconds guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00libguestfs: trace: is_file = 0 libguestfs: trace: is_dir "/log" seconds guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 38 (is_dir) took 0.00 libguestfs: trace: is_dir 0 libguestfs: trace: case_sensitive_path "/windows" seconds guestfsd: main_loop: new request, len 0x34 libguestfs: trace: case_sensitive_path = "/windows" libguestfs: trace: case_sensitive_path "/windows/system32" guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.02 seconds guestfsd: main_loop: new request, len 0x40 guestfsd: error: windows: no file or directory found with this name libguestfs: trace: case_sensitive_path = NULL (error) libguestfs: trace: case_sensitive_path "/winnt" guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/winnt" libguestfs: trace: case_sensitive_path "/winnt/system32" guestfsd: main_loop: new request, len 0x3c guestfsd: error: winnt: no file or directory found with this name guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = NULL (error) libguestfs: trace: case_sensitive_path "/win32" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/win32" libguestfs: trace: case_sensitive_path "/win32/system32" guestfsd: main_loop: new request, len 0x3c guestfsd: error: win32: no file or directory found with this name guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = NULL (error) libguestfs: trace: case_sensitive_path "/win" guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/win" libguestfs: trace: case_sensitive_path "/win/system32" guestfsd: main_loop: new request, len 0x3c guestfsd: error: win: no file or directory found with this name guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = NULL (error) libguestfs: trace: case_sensitive_path "/boot.ini" guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/boot.ini" libguestfs: trace: is_file "/boot.ini" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: case_sensitive_path "/System Volume Information" guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/System Volume Information" libguestfs: trace: is_dir "/System Volume Information" guestfsd: main_loop: new request, len 0x4c guestfsd: main_loop: proc 38 (is_dir) took 0.00 secondslibguestfs: trace: is_dir = 1 libguestfs: trace: case_sensitive_path "/Program Files" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/Program Files" libguestfs: trace: is_dir "/Program Files" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: case_sensitive_path "/System Volume Information" guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds libguestfs: trace: case_sensitive_path = "/System Volume Information" libguestfs: trace: is_dir "/System Volume Information" guestfsd: main_loop: new request, len 0x4c guestfsd: main_loop: proc 38 (is_dir) took 0.00 secondslibguestfs: trace: is_dir = 1 libguestfs: trace: umount_all guestfsd: main_loop: new request, len 0x28 umount-all: /proc/mounts: fsname=rootfs dir=/ type=rootfs opts=rw 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=/dev/root dir=/ type=ext2 opts=rw,noatime 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=98788k,mode=755 freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=243872k,nr_inodes=60968,mode=755 freq=0 passno=0 umount-all: /proc/mounts: fsname=fusectl dir=/sys/fs/fuse/connections type=fusectl opts=rw,relatime freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev/sda1 dir=/sysroot type=fuseblk opts=ro,relatime,user_id=0,group_id=0,allow_other,blksize=4096 freq=0 passno=0 umount /sysroot libguestfs: trace: umount_all = 0 libguestfs: trace: vfs_type "/dev/sda2" guestfsd: main_loop: proc 47 (umount_all) took 0.01 seconds guestfsd: main_loop: new request, len 0x38 blkid -c /dev/null -o value -s TYPE /dev/sda2 libguestfs: trace: vfs_type = "ntfs" libguestfs: check_for_filesystem_on: /dev/sda2 (ntfs) libguestfs: trace: internal_parse_mountable "/dev/sda2" guestfsd: main_loop: proc 198 (vfs_type) took 0.00 seconds guestfsd: main_loop: new request, len 0x38 libguestfs: trace: internal_parse_mountable = <struct guestfs_internal_mountable *> libguestfs: trace: is_whole_device "/dev/sda2" guestfsd: main_loop: proc 396 (internal_parse_mountable) took 0.00 seconds guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 395 (is_whole_device) took 0.00 seconds libguestfs: trace: is_whole_device = 0 libguestfs: trace: mount_ro "/dev/sda2" "/" guestfsd: main_loop: new request, len 0x40 mount -o ro /dev/sda2 /sysroot/ libguestfs: trace: mount_ro = 0 libguestfs: trace: part_to_partnum "/dev/sda2" guestfsd: main_loop: proc 73 (mount_ro) took 0.14 seconds guestfsd: main_loop: new request, len 0x38 guestfsd: main_loop: proc 293 (plibguestfs: trace: part_to_partnum = 2 libguestfs: trace: is_dir "/etc" art_to_partnum) took 0.00 seconds guestfsd: main_loop: new request, len 0x34 libguestfs: trace: is_dir = 0 libguestfs: trace: is_dir "/bin" guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 38 (is_dir) took 0.00 secondslibguestfs: trace: is_dir = 0 libguestfs: trace: is_dir "/share" guestfsd: main_loop: new request, len 0x38 guestfsd: main_llibguestfs: trace: is_dir = 0 libguestfs: trace: is_file "/grub/menu.lst" oop: proc 38 (is_dir) took 0.00 seconds guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (islibguestfs: trace: is_file = 0 libguestfs: trace: is_file "/grub/grub.conf" _file) took 0.00 seconds guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/grub2/grub.cfg" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_file "/hurd/console" guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 0 libguestfs: trace: is_dir "/log" guestfsd: main_loop: new request, len 0x34 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 0 libguestfs: trace: case_sensitive_path "/windows" guestfsd: main_loop: new request, len 0x34 libguestfs: trace: case_sensitive_path = "/Windows" libguestfs: trace: case_sensitive_path "/Windows/system32" guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds guestfsd: main_loop: new request, len 0x40 libguestfs: trace: case_sensitive_path = "/Windows/System32" libguestfs: trace: is_dir "/Windows/System32" guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.01 seconds guestfsd: main_loop: new request, len 0x44 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 1 libguestfs: trace: case_sensitive_path "/Windows/system32/config" guestfsd: main_loop: new request, len 0x44 libguestfs: trace: case_sensitive_path = "/Windows/System32/config" libguestfs: trace: is_dir "/Windows/System32/config" guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.13 seconds guestfsd: main_loop: new request, len 0x48 guestfsd: main_loop: proc 38 (is_dir) took 0.00 seconds libguestfs: trace: is_dir = 1 libguestfs: trace: case_sensitive_path "/Windows/system32/cmd.exe" guestfsd: main_loop: new request, len 0x48 libguestfs: trace: case_sensitive_path = "/Windows/System32/cmd.exe" libguestfs: trace: is_file "/Windows/System32/cmd.exe" guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds guestfsd: main_loop: new request, len 0x4c guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 1 libguestfs: windows %SYSTEMROOT% = /Windows libguestfs: trace: case_sensitive_path "/Windows/system32/cmd.exe" guestfsd: main_loop: new request, len 0x48 libguestfs: trace: case_sensitive_path = "/Windows/System32/cmd.exe" libguestfs: trace: file_architecture "/Windows/System32/cmd.exe" libguestfs: trace: file "/Windows/System32/cmd.exe" guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds guestfsd: main_loop: new request, len 0x48 file -zb /sysroot/Windows/System32/cmd.exe libguestfs: trace: file = "PE32+ executable (console) x86-64, for MS Windows" libguestfs: trace: file_architecture = "x86_64" libguestfs: trace: case_sensitive_path "/Windows/system32/config/software" guestfsd: main_loop: proc 49 (file) took 0.04 seconds guestfsd: main_loop: new request, len 0x50 libguestfs: trace: case_sensitive_path = "/Windows/System32/config/SOFTWARE" libguestfs: trace: is_file "/Windows/System32/config/SOFTWARE" guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.04 seconds guestfsd: main_loop: new request, len 0x54 guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds libguestfs: trace: is_file = 1 libguestfs: trace: hivex_open "/Windows/System32/config/SOFTWARE" "verbose:true" guestfsd: main_loop: new request, len 0x5c hivex: hivex_open: successfully read Windows Registry hive file: pages: 8593 [sml: 4096, lge: 16384] blocks: 712962 [sml: 8, avg: 53, lge: 16352] blocks used: 697410 bytes used: 36983920 libguestfs: trace: hivex_open = 0 libguestfs: trace: hivex_root guestfsd: main_loop: proc 350 (hivex_open) took 0.15 seconds guestfsd: main_loop: new request, len 0x28 guestfsd: main_loop: proc 352 (hivex_root) took 0.00 seconds libguestfs: trace: hivex_root = 4128 libguestfs: trace: hivex_node_get_child 4128 "Microsoft" guestfsd: main_loop: new request, len 0x40 libguestfs: trace: hivex_node_get_child = 4448 libguestfs: trace: hivex_node_get_child 4448 "Windows NT" guestfsd: main_loop: proc 355 (hivex_node_get_child) took 0.00 seconds guestfsd: main_loop: new request, len 0x40 libguestfs: trace: hivex_node_get_child = 104216 libguestfs: trace: hivex_node_get_child 104216 "CurrentVersion" guestfsd: main_loop: proc 355 (hivex_node_get_child) took 0.00 seconds guestfsd: main_loop: new request, len 0x44 libguestfs: trace: hivex_node_get_child = 104312 libguestfs: trace: hivex_node_values 104312 guestfsd: main_loop: proc 355 (hivex_node_get_child) took 0.00 seconds guestfsd: main_loop: new request, len 0x30 libguestfs: trace: hivex_node_values = <struct guestfs_hivex_value_list *> libguestfs: trace: hivex_value_key 4496744 guestfsd: main_loop: proc 357 (hivex_node_values) took 0.00 seconds guestfsd: main_loop: new request, len 0x30 libguestfs: trace: hivex_value_key = "CurrentVersion" libguestfs: trace: hivex_value_utf8 4496744 libguestfs: trace: hivex_value_value 4496744 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds guestfsd: main_loop: new request, len 0x30 libguestfs: trace: hivex_value_value = "6\x00.\x001\x00\x00\x00" libguestfs: trace: hivex_value_utf8 = "6.1" libguestfs: trace: hivex_value_key 4496792 guestfsd: main_loop: proc 361 (hivex_value_value) took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "CurrentBuild" libguestfs: trace: hivex_value_key 4496848 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "SoftwareType" libguestfs: trace: hivex_value_key 4481040 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "CurrentType" libguestfs: trace: hivex_value_key 4481344 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "InstallDate" libguestfs: trace: hivex_value_key 4481880 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "RegisteredOrganization" libguestfs: trace: hivex_value_key 4481928 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "RegisteredOwner" libguestfs: trace: hivex_value_key 4482016 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "SystemRoot" libguestfs: trace: hivex_value_key 29425096 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "InstallationType" libguestfs: trace: hivex_value_utf8 29425096 libguestfs: trace: hivex_value_value 29425096 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 361 (hivex_value_value) took 0.00 seconds libguestfs: trace: hivex_value_value "C\x00l\x00i\x00e\x00n\x00t\x00\x00\x00" libguestfs: trace: hivex_value_utf8 = "Client" libguestfs: trace: hivex_value_key 29425160 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "EditionID" libguestfs: trace: hivex_value_key 4482088 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "ProductName" libguestfs: trace: hivex_value_utf8 4482088 libguestfs: trace: hivex_value_value 4482088 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 361 (hivex_value_value) took 0.00 seconds libguestfs: trace: hivex_value_value = "W\x00i\x00n\x00d\x00o\x00w\x00s\x00 \x007\x00 \x00U\x00l\x00t\x00i\x00m\x00a\x00t\x00e\x00\x00\x00" libguestfs: trace: hivex_value_utf8 = "Windows 7 Ultimate" libguestfs: trace: hivex_value_key 11809408 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "ProductId" libguestfs: trace: hivex_value_key 12809376 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "DigitalProductId" libguestfs: trace: hivex_value_key 29052808 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "DigitalProductId4" libguestfs: trace: hivex_value_key 32802208 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "CurrentBuildNumber" libguestfs: trace: hivex_value_key 29050992 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "BuildLab" libguestfs: trace: hivex_value_key 32800272 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "BuildLabEx" libguestfs: trace: hivex_value_key 32802488 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "BuildGUID" libguestfs: trace: hivex_value_key 32802328 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "CSDBuildNumber" libguestfs: trace: hivex_value_key 1936496 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "PathName" libguestfs: trace: hivex_value_key 11809336 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "CSDVersion" libguestfs: trace: hivex_close guestfsd: main_loop: new request, len 0x28 hivex: hivex_close: hivex_close libguestfs: trace: hivex_close = 0 libguestfs: trace: case_sensitive_path "/Windows/system32/config/system" guestfsd: main_loop: proc 351 (hivex_close) took 0.00 seconds guestfsd: main_loop: new request, len 0x4c libguestfs: trace: case_sensitive_path = "/Windows/System32/config/SYSTEM" libguestfs: trace: is_file "/Windows/System32/config/SYSTEM" guestfsd: main_loop: proc 197 (case_sensitive_path) took 0.00 seconds guestfsd: main_loop: new request, len 0x50 libguestfs: trace: is_file = 1 libguestfs: trace: hivex_open "/Windows/System32/config/SYSTEM" "verbose:true" guestfsd: main_loop: proc 37 (is_file) took 0.00 seconds guestfsd: main_loop: new request, len 0x58 hivex: hivex_open: successfully read Windows Registry hive file: pages: 2222 [sml: 4096, lge: 16384] blocks: 160340 [sml: 8, avg: 62, lge: 16352] blocks used: 155825 bytes used: 9897016 guestfsd: main_loop: proc 350 (hivex_open) took 0.06 secondslibguestfs: trace: hivex_open = 0 libguestfs: trace: hivex_root guestfsd: main_loop: new request, len 0x28 guestfsd: main_loop: proc 352 (hivex_root) took 0.00 seconds libguestfs: trace: hivex_root = 4128 libguestfs: trace: hivex_node_get_child 4128 "Select" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 355 (hivex_node_get_child) took 0.00 seconds libguestfs: trace: hivex_node_get_child = 592040 libguestfs: trace: hivex_node_get_value 592040 "Current" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 358 (hivex_node_get_valibguestfs: trace: hivex_node_get_value = 592168 libguestfs: trace: hivex_value_value 592168 lue) took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 361 (hivex_value_value) took 0.00 seconds libguestfs: trace: hivex_value_value = "\x01\x00\x00\x00" libguestfs: trace: hivex_node_get_child 4128 "MountedDevices" guestfsd: main_loop: new request, len 0x44 guestfsd: main_loop: proc 355 (hivex_node_get_chlibguestfs: trace: hivex_node_get_child = 3013264 libguestfs: trace: hivex_node_values 3013264 ild) took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 357 (hivex_node_values) took 0.00 seconds libguestfs: trace: hivex_node_values = <struct guestfs_hivex_value_list *> libguestfs: trace: hivex_value_key 3013360 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) libguestfs: trace: hivex_value_key = "\DosDevices\C:" libguestfs: trace: hivex_value_key 3323536 took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) libguestfs: trace: hivex_value_key = "\??\Volume{797fade1-df05-11e4-9736-806e6f6e6963}" libguestfs: trace: hivex_value_key 3323608 took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key "\??\Volume{797fade2-df05-11e4-9736-806e6f6e6963}" libguestfs: trace: hivex_value_key 3421288 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) libguestfs: trace: hivex_value_key = "\??\Volume{797fade5-df05-11e4-9736-806e6f6e6963}" libguestfs: trace: hivex_value_key 3421608 took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key "\??\Volume{797fade6-df05-11e4-9736-806e6f6e6963}" libguestfs: trace: hivex_value_key 3441224 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "\DosDevices\D:" libguestfs: trace: hivex_value_key 3441544 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "\DosDevices\E:" libguestfs: trace: hivex_value_key 3013360 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "\DosDevices\C:" libguestfs: trace: hivex_value_type 3013360 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 360 (hivex_value_type) took 0.00 seconds libguestfs: trace: hivex_value_type = 3 libguestfs: trace: hivex_value_value 3013360 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 361 (hivex_value_value) took 0.00 seconds libguestfs: trace: hivex_value_value "K\x8dN\xb6\x00\x00P\x06\x00\x00\x00\x00" libguestfs: trace: list_devices guestfsd: main_loop: new request, len 0x28 guestfsd: main_loop: proc 7 (list_devices) took 0.00 secondslibguestfs: trace: list_devices = ["/dev/sda"] libguestfs: trace: pread_device "/dev/sda" 4 440 guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 276 (pread_device) toolibguestfs: trace: pread_device = "K\x8dN\xb6" libguestfs: trace: part_list "/dev/sda" k 0.00 seconds guestfsd: main_loop: new request, len 0x34 parted -s -m /dev/null Error: The device /dev/null is so small that it cannot possibly store a file system or partition table. Perhaps you selected the wrong device? Warning: Error fsyncing/closing /dev/null: Invalid argument parted -m -- /dev/sda unit b print libguestfs: trace: part_list = <struct guestfs_partition_list *> libguestfs: trace: hivex_value_key 3323536 guestfsd: main_loop: proc 213 (part_list) took 0.21 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key "\??\Volume{797fade1-df05-11e4-9736-806e6f6e6963}" libguestfs: trace: hivex_value_key 3323608 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key "\??\Volume{797fade2-df05-11e4-9736-806e6f6e6963}" libguestfs: trace: hivex_value_key 3421288 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key "\??\Volume{797fade5-df05-11e4-9736-806e6f6e6963}" libguestfs: trace: hivex_value_key 3421608 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key "\??\Volume{797fade6-df05-11e4-9736-806e6f6e6963}" libguestfs: trace: hivex_value_key 3441224 guestfsd: main_loop: new request, len 0x30 libguestfs: trace: hivex_value_key = "\DosDevices\D:" libguestfs: trace: hivex_value_type 3441224 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 360 (hivex_value_type) took 0.00 seconds libguestfs: trace: hivex_value_type = 3 libguestfs: trace: hivex_value_value 3441224 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 361 (hivex_value_value) took 0.00 seconds libguestfs: trace: hivex_value_value "\\x00?\x00?\x00\\x00I\x00D\x00E\x00#\x00C\x00d\x00R\x00o\x00m\x00Q\x00E\x00M\x00U\x00_\x00Q\x00E\x00M\x00U\x00_\x00D\x00V\x00D\x00-\x00R\x00O\x00M\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x002\x00.\x001\x00.\x00_\x00_\x00_\x00_\x00#\x005\x00&\x002\x007\x007\x000\x00a\x007\x00a\x00f\x00&\x000\x00&\x000\x00.\x000\x00.\x000\x00#\x00{\x005\x003\x00f\x005\x006\x003\x000\x00d\x00-\x00b\x006\x00b\x00f\x00-\x001\x001\x00d\x000\x00-\x009\x004\x00f\x002\x00-\x000\x000\x00a\x000\x00c\x009\x001\x00e\x00f\x00b\x008\x00b\x00}\x00" libguestfs: trace: hivex_value_key 3441544 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "\DosDevices\E:" libguestfs: trace: hivex_value_type 3441544 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 360 (hivex_value_type) took 0.00 seconds libguestfs: trace: hivex_value_type = 3 libguestfs: trace: hivex_value_value 3441544 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 361 (hivex_value_value) took 0.00 secondslibguestfs: trace: hivex_value_value "\\x00?\x00?\x00\\x00I\x00D\x00E\x00#\x00C\x00d\x00R\x00o\x00m\x00Q\x00E\x00M\x00U\x00_\x00Q\x00E\x00M\x00U\x00_\x00D\x00V\x00D\x00-\x00R\x00O\x00M\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x00_\x002\x00.\x001\x00.\x00_\x00_\x00_\x00_\x00#\x005\x00&\x002\x007\x007\x000\x00a\x007\x00a\x00f\x00&\x000\x00&\x000\x00.\x001\x00.\x000\x00#\x00{\x005\x003\x00f\x005\x006\x003\x000\x00d\x00-\x00b\x006\x00b\x00f\x00-\x001\x001\x00d\x000\x00-\x009\x004\x00f\x002\x00-\x000\x000\x00a\x000\x00c\x009\x001\x00e\x00f\x00b\x008\x00b\x00}\x00" libguestfs: trace: hivex_node_get_child 4128 "ControlSet001" guestfsd: main_loop: new request, len 0x44 guestfsd: main_loop: proc 355 (hivex_node_get_child) took 0.00 seconds libguestfs: trace: hivex_node_get_child = 4448 libguestfs: trace: hivex_node_get_child 4448 "Services" guestfsd: main_loop: new request, len 0x3c guestfsd: main_loop: proc 355 (hivex_node_get_child) took 0.00 seconds libguestfs: trace: hivex_node_get_child = 4560 libguestfs: trace: hivex_node_get_child 4560 "Tcpip" guestfsd: main_loop: new request, len 0x3c libguestfs: trace: hivex_node_get_child = 241696 libguestfs: trace: hivex_node_get_child 241696 "Parameters" guestfsd: main_loop: proc 355 (hivex_node_get_child) took 0.00 seconds guestfsd: main_loop: new request, len 0x40 guestfsd: main_loop: proc 355 (hivex_node_get_chlibguestfs: trace: hivex_node_get_child = 484384 libguestfs: trace: hivex_node_values 484384 ild) took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 357 (hivex_node_values) took 0.00 seconds libguestfs: trace: hivex_node_values = <struct guestfs_hivex_value_list *> libguestfs: trace: hivex_value_key 484480 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "ICSDomain" libguestfs: trace: hivex_value_key 642048 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 secondslibguestfs: trace: hivex_value_key = "SyncDomainWithMembership" libguestfs: trace: hivex_value_key 3152384 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "NV Hostname" libguestfs: trace: hivex_value_key 3767280 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 secondlibguestfs: trace: hivex_value_key = "DataBasePath" libguestfs: trace: hivex_value_key 3767416 s guestfsd: main_loop: new request, len 0x30 guestfsd: main_llibguestfs: trace: hivex_value_key = "NameServer" libguestfs: trace: hivex_value_key 3767456 oop: proc 359 (hivex_value_key) took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "ForwardBroadcasts" libguestfs: trace: hivex_value_key 3767536 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "IPEnableRouter" libguestfs: trace: hivex_value_key 3815600 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "Domain" libguestfs: trace: hivex_value_key 3767504 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "Hostname" libguestfs: trace: hivex_value_utf8 3767504 libguestfs: trace: hivex_value_value 3767504 guestfsd: main_loop: new request, len 0x30 libguestfs: trace: hivex_value_value "P\x00e\x00t\x00e\x00r\x00-\x00P\x00C\x00\x00\x00" libguestfs: trace: hivex_value_utf8 = "Peter-PC" libguestfs: trace: hivex_value_key 3815712 guestfsd: main_loop: proc 361 (hivex_value_value) took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 seconds libguestfs: trace: hivex_value_key = "SearchList" libguestfs: trace: hivex_value_key 3815800 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 secondslibguestfs: trace: hivex_value_key = "UseDomainNameDevolution" libguestfs: trace: hivex_value_key 3815848 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) libguestfs: trace: hivex_value_key = "EnableICMPRedirect" libguestfs: trace: hivex_value_key 3815752 took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 secondslibguestfs: trace: hivex_value_key = "DeadGWDetectDefault" libguestfs: trace: hivex_value_key 3815952 guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) libguestfs: trace: hivex_value_key = "DontAddDefaultGatewayDefault" libguestfs: trace: hivex_value_key 8331744 took 0.00 seconds guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 secondlibguestfs: trace: hivex_value_key = "EnableWsd" libguestfs: trace: hivex_value_key 8331784 s guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 secondlibguestfs: trace: hivex_value_key = "QualifyingDestinationThreshold" libguestfs: trace: hivex_value_key 6918424 s guestfsd: main_loop: new request, len 0x30 guestfsd: main_loop: proc 359 (hivex_value_key) took 0.00 secondslibguestfs: trace: hivex_value_key = "DhcpNameServer" libguestfs: trace: hivex_close guestfsd: main_loop: new request, len 0x28 hivex: hivex_close: hivex_close libguestfs: trace: hivex_close = 0 libguestfs: trace: umount_all guestfsd: main_loop: proc 351 (hivex_close) took 0.00 seconds guestfsd: main_loop: new request, len 0x28 umount-all: /proc/mounts: fsname=rootfs dir=/ type=rootfs opts=rw 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=/dev/root dir=/ type=ext2 opts=rw,noatime 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=98788k,mode=755 freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=243872k,nr_inodes=60968,mode=755 freq=0 passno=0 umount-all: /proc/mounts: fsname=fusectl dir=/sys/fs/fuse/connections type=fusectl opts=rw,relatime freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev/sda2 dir=/sysroot type=fuseblk opts=ro,relatime,user_id=0,group_id=0,allow_other,blksize=4096 freq=0 passno=0 umount /sysroot libguestfs: trace: umount_all = 0 libguestfs: trace: inspect_get_roots libguestfs: trace: inspect_get_roots = ["/dev/sda2"] libguestfs: trace: inspect_os = ["/dev/sda2"] libguestfs: trace: inspect_get_mountpoints "/dev/sda2" libguestfs: trace: inspect_get_mountpoints = ["/", "/dev/sda2"] libguestfs: trace: mount "/dev/sda2" "/" guestfsd: main_loop: proc 47 (umount_all) took 0.02 seconds guestfsd: main_loop: new request, len 0x40 mount -o /dev/sda2 /sysroot/ The disk contains an unclean file system (0, 0). The file system wasn't safely closed on Windows. Fixing. libguestfs: trace: mount = 0 libguestfs: trace: checksums_out "sha1" "/" "/tmp/tmpAWHkYv" guestfsd: main_loop: proc 1 (mount) took 2.02 seconds guestfsd: main_loop: new request, len 0x38 cd /sysroot/ && find -type f -print0 | xargs -0 sha1sum [ 25.580340] perf interrupt took too long (2540 > 2500), lowering kernel.perf_event_max_sample_rate to 50000 sha1sum: ./Windows/Prefetch/ReadyBoot/Trace7.fx: Value too large for defined data type [ 67.835952] perf interrupt took too long (5048 > 5000), lowering kernel.perf_event_max_sample_rate to 25000 [ 143.304037] perf interrupt took too long (10010 > 10000), lowering kernel.perf_event_max_sample_rate to 12500 pclose: /: Success guestfsd: main_loop: proc 244 (checksums_out) took 245.25 seconds libguestfs: trace: checksums_out = -1 (error) libguestfs: trace: close libguestfs: closing guestfs handle 0x2b45cc0 (state 2) libguestfs: trace: internal_autosync guestfsd: main_loop: new request, len 0x28 umount-all: /proc/mounts: fsname=rootfs dir=/ type=rootfs opts=rw 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=/dev/root dir=/ type=ext2 opts=rw,noatime 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=98788k,mode=755 freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev dir=/dev type=devtmpfs opts=rw,relatime,size=243872k,nr_inodes=60968,mode=755 freq=0 passno=0 umount-all: /proc/mounts: fsname=fusectl dir=/sys/fs/fuse/connections type=fusectl opts=rw,relatime freq=0 passno=0 umount-all: /proc/mounts: fsname=/dev/sda2 dir=/sysroot type=fuseblk opts=rw,relatime,user_id=0,group_id=0,allow_other,blksize=4096 freq=0 passno=0 umount /sysroot fsync /dev/sda guestfsd: main_loop: proc 282 (internal_autosync) took 0.26 seconds libguestfs: trace: internal_autosync = 0 libguestfs: sending SIGTERM to process 10112 libguestfs: command: run: rm libguestfs: command: run: \ -rf /tmp/libguestfssil2bk Traceback (most recent call last): File "/home/noxdafox/see/src/see/observer.py", line 128, in synchronous function(*args, **kwargs) File "/home/noxdafox/see_hooks/filesystem_diff.py", line 95, in stop_processing files1 = concurrent_list_files(self.disks[0]) File "/home/noxdafox/see_hooks/filesystem_diff.py", line 130, in concurrent_list_files fs.checksums_out('sha1', '/', files.name) File "/usr/lib/python2.7/dist-packages/guestfs.py", line 1427, in checksums_out r = libguestfsmod.checksums_out (self._o, csumtype, directory, sumsfile) RuntimeError: file receive cancelled by daemon
Daniel P. Berrange
2015-May-28 11:10 UTC
Re: [Libguestfs] Concurrent scanning of same disk
On Wed, May 27, 2015 at 09:38:38AM +0300, NoxDaFox wrote:> Greetings, > > I am suffering of several weird errors which show randomly and make me > suspect some concurrency issue. > > Libguestfs version is 1.28.1, linux kernel 3.16, libvirt 1.2.9 and qemu 2.1. > > What I'm trying to do is comparing the disk state at two different point of > a guest execution. > Disk snapshots are taken through libvirt in different moments (I am aware > of caching issue), from such snapshots, new disks are created using the > "qemu-img convert" command with backing file pointing to the disk being > executed.Am I right in understanding that the guest VM is /running/ while you invoke 'qemu-img convert' ? If so, that is bad practice - it is not supported to invoke qemu-img against /any/ disk image that is currently open by a running QEMU process. You /might/ get away with it if the disk image is raw, since you'll (usually) just end up with a dirty guest filesystem that can be recovered by replaying the journal. With qcow2 though it is worse because QEMU may have in-memory changes to the qcow2 sector allocation tables which are in-flight and so qemu-img can end up with inconsistent data. You really need to use the libvirt live snapshot APIs/tools to take copies of disks attached to running VMs. This further gives the option to quiesce the guest, if consistency of guest OS data is desired too. There are quite a few ways to use the libvirt APIs, but one example is shown here http://wiki.libvirt.org/page/Live-disk-backup-with-active-blockcommit Regards, Daniel -- |: http://berrange.com -o- http://www.flickr.com/photos/dberrange/ :| |: http://libvirt.org -o- http://virt-manager.org :| |: http://autobuild.org -o- http://search.cpan.org/~danberr/ :| |: http://entangle-photo.org -o- http://live.gnome.org/gtk-vnc :|