pkoutoupis / rapiddisk

An Advanced Linux RAM Drive and Caching kernel modules. Dynamically allocate RAM as block devices. Use them as stand alone drives or even map them as caching nodes to slower local disk drives. Access those volumes locally or export them across an NVMe Target network. Manage it all from a web API.
http://www.rapiddisk.org
GNU General Public License v2.0
299 stars 49 forks source link

Crashes while using it in a VM #13

Closed JvdW closed 8 years ago

JvdW commented 8 years ago

Its not stated explicitly but I think it should work when used inside a VM. I tried that multiple times but they all end with a lockup of the VM. Tried:

Q: should this work or is there a problem with the different setups that I have tried?

Regards,

Joop

pkoutoupis commented 8 years ago

Is there a stack dump on tty1 or in dmesg?

JvdW commented 8 years ago

On 13-11-2016 16:00, Petros Koutoupis wrote:

Is there a stack dump on tty1 or in dmesg?

No, problem is that the VM becomes completely unresponsive, cpu usage goes to 100% and only way to get back in is to power it down meaning loss of all unwritten data. So messages looks like the attached file. Logged in this morning and did:

  • rapiddisk --attach 256 # VM has 2G RAM and is idle
  • rapiddisk --cache-map rd0 /dev/vda1
  • mount /dev/mapper/rc-wt_vda1 /cache
  • cp -pr ./geoserver /cache # copies quite a bit files, but don't know how much
  • power off
  • restart

Anything I can do to debug this?

Joop

Nov 14 07:47:03 rpmrepo01 puppet-agent[27875]: Finished catalog run in 9.38 seconds Nov 14 08:17:07 rpmrepo01 puppet-agent[29283]: Finished catalog run in 11.33 seconds Nov 14 08:44:58 rpmrepo01 kernel: rapiddisk: Attached rd0 of 268435456 bytes in size. Nov 14 08:46:23 rpmrepo01 kernel: device-mapper: rapiddisk-cache: Allocate 512KB (8B per) mem for 65536-entry cache(capacity:256MB, associativity:512, block size:8 sectors(4KB)) Nov 14 08:46:23 rpmrepo01 kernel: ------------[ cut here ]------------ Nov 14 08:46:23 rpmrepo01 kernel: WARNING: at kernel/softirq.c:159 local_bh_enable_ip+0x7b/0xa0() (Not tainted) Nov 14 08:46:23 rpmrepo01 kernel: Hardware name: oVirt Node Nov 14 08:46:23 rpmrepo01 kernel: Modules linked in: ip6t_REJECT nf_conntrack_ipv6 nf_defrag_ipv6 xt_state nf_conntrack ip6table_filter ip6_tables ipv6 ext2 rapiddisk_cache(U) rapiddisk(U) dm_crypt virtio_console virtio_net i2c_piix4 i2c_core sg ext4 jbd2 mbcache virtio_blk sr_mod cdrom virtio_pci virtio_ring virtio pata_acpi ata_generic ata_piix dm_mirror dm_region_hash dm_log dm_mod [last unloaded: scsi_wait_scan] Nov 14 08:46:23 rpmrepo01 kernel: Pid: 0, comm: swapper Not tainted 2.6.32-642.6.2.el6.x86_64 #1 Nov 14 08:46:23 rpmrepo01 kernel: Call Trace: Nov 14 08:46:23 rpmrepo01 kernel: [] ? warn_slowpath_common+0x91/0xe0 Nov 14 08:46:23 rpmrepo01 kernel: [] ? warn_slowpath_null+0x1a/0x20 Nov 14 08:46:23 rpmrepo01 kernel: [] ? local_bh_enable_ip+0x7b/0xa0 Nov 14 08:46:23 rpmrepo01 kernel: [] ? _spin_unlock_bh+0x1b/0x20 Nov 14 08:46:23 rpmrepo01 kernel: [] ? rc_io_callback+0x151/0x3b0 [rapiddisk_cache] Nov 14 08:46:23 rpmrepo01 kernel: [] ? rc_io_callback+0x0/0x3b0 [rapiddisk_cache] Nov 14 08:46:23 rpmrepo01 kernel: [] ? dec_count+0x6f/0x80 [dm_mod] Nov 14 08:46:23 rpmrepo01 kernel: [] ? endio+0x4a/0x70 [dm_mod] Nov 14 08:46:23 rpmrepo01 kernel: [] ? bio_endio+0x1d/0x40 Nov 14 08:46:23 rpmrepo01 kernel: [] ? req_bio_endio+0x9b/0xe0 Nov 14 08:46:23 rpmrepo01 kernel: [] ? blk_update_request+0x117/0x4c0 Nov 14 08:46:23 rpmrepo01 kernel: [] ? blk_update_bidi_request+0x27/0xa0 Nov 14 08:46:23 rpmrepo01 kernel: [] ? __blk_end_request_all+0x2e/0x60 Nov 14 08:46:23 rpmrepo01 kernel: [] ? blk_done+0x4a/0x110 [virtio_blk] Nov 14 08:46:23 rpmrepo01 kernel: [] ? vring_interrupt+0x3c/0xe0 [virtio_ring] Nov 14 08:46:23 rpmrepo01 kernel: [] ? handle_IRQ_event+0x60/0x170 Nov 14 08:46:23 rpmrepo01 kernel: [] ? handle_edge_irq+0xde/0x180 Nov 14 08:46:23 rpmrepo01 kernel: [] ? handle_irq+0x49/0xa0 Nov 14 08:46:23 rpmrepo01 kernel: [] ? do_IRQ+0x6c/0xf0 Nov 14 08:46:23 rpmrepo01 kernel: [] ? ret_from_intr+0x0/0x11 Nov 14 08:46:23 rpmrepo01 kernel: [] ? native_safe_halt+0xb/0x10 Nov 14 08:46:23 rpmrepo01 kernel: [] ? default_idle+0x4d/0xb0 Nov 14 08:46:23 rpmrepo01 kernel: [] ? cpu_idle+0xb6/0x110 Nov 14 08:46:23 rpmrepo01 kernel: [] ? rest_init+0x7a/0x80 Nov 14 08:46:23 rpmrepo01 kernel: [] ? start_kernel+0x429/0x436 Nov 14 08:46:23 rpmrepo01 kernel: [] ? x86_64_start_reservations+0x125/0x129 Nov 14 08:46:23 rpmrepo01 kernel: [] ? x86_64_start_kernel+0x115/0x124 Nov 14 08:46:23 rpmrepo01 kernel: ---[ end trace 457bda29f3f119e2 ]--- Nov 14 08:47:03 rpmrepo01 puppet-agent[31048]: Finished catalog run in 10.17 seconds Nov 14 08:47:41 rpmrepo01 kernel: SGI XFS with ACLs, security attributes, large block/inode numbers, no debug enabled Nov 14 08:47:41 rpmrepo01 kernel: SGI XFS Quota Management subsystem Nov 14 08:47:41 rpmrepo01 kernel: XFS (dm-2): Mounting Filesystem Nov 14 08:47:42 rpmrepo01 kernel: XFS (dm-2): Starting recovery (logdev: internal) Nov 14 08:47:42 rpmrepo01 kernel: XFS (dm-2): Ending recovery (logdev: internal) Nov 14 08:52:17 rpmrepo01 kernel: imklog 5.8.10, log source = /proc/kmsg started. Nov 14 08:52:17 rpmrepo01 rsyslogd: [origin software="rsyslogd" swVersion="5.8.10" x-pid="1408" x-info="http://www.rsyslog.com"] start Nov 14 08:52:17 rpmrepo01 kernel: Initializing cgroup subsys cpuset Nov 14 08:52:17 rpmrepo01 kernel: Initializing cgroup subsys cpu Nov 14 08:52:17 rpmrepo01 kernel: Linux version 2.6.32-642.6.2.el6.x86_64 (mockbuild@worker1.bsys.centos.org) (gcc version 4.4.7 20120313 (Red Hat 4.4.7-17) (GCC) ) #1 SMP Wed Oct 26 06:52:09 UTC 2016 Nov 14 08:52:17 rpmrepo01 kernel: Command line: ro root=/dev/mapper/vg_rpmrepo01-lv_root rd_NO_LUKS LANG=en_US.UTF-8 rd_LVM_LV=vg_rpmrepo01/lv_root rd_NO_MD SYSFONT=latarcyrheb-sun16 crashkernel=auto KEYBOARDTYPE=pc KEYTABLE=us rd_NO_DM rhgb quiet Nov 14 08:52:17 rpmrepo01 kernel: KERNEL supported cpus: Nov 14 08:52:17 rpmrepo01 kernel: Intel GenuineIntel Nov 14 08:52:17 rpmrepo01 kernel: AMD AuthenticAMD Nov 14 08:52:17 rpmrepo01 kernel: Centaur CentaurHauls Nov 14 08:52:17 rpmrepo01 kernel: Disabled fast string operations Nov 14 08:52:17 rpmrepo01 kernel: BIOS-provided physical RAM map: Nov 14 08:52:17 rpmrepo01 kernel: BIOS-e820: 0000000000000000 - 000000000009bc00 (usable) Nov 14 08:52:17 rpmrepo01 kernel: BIOS-e820: 000000000009bc00 - 00000000000a0000 (reserved) Nov 14 08:52:17 rpmrepo01 kernel: BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved) Nov 14 08:52:17 rpmrepo01 kernel: BIOS-e820: 0000000000100000 - 000000007fffc000 (usable) Nov 14 08:52:17 rpmrepo01 kernel: BIOS-e820: 000000007fffc000 - 0000000080000000 (reserved) Nov 14 08:52:17 rpmrepo01 kernel: BIOS-e820: 00000000fffbc000 - 0000000100000000 (reserved) Nov 14 08:52:17 rpmrepo01 kernel: SMBIOS version 2.4 @ 0xFD8F0 Nov 14 08:52:17 rpmrepo01 kernel: SMBIOS 2.4 present. Nov 14 08:52:17 rpmrepo01 kernel: Hypervisor detected: KVM Nov 14 08:52:17 rpmrepo01 kernel: last_pfn = 0x7fffc max_arch_pfn = 0x400000000 Nov 14 08:52:17 rpmrepo01 kernel: x86 PAT enabled: cpu 0, old 0x70106, new 0x7010600070106 Nov 14 08:52:17 rpmrepo01 kernel: init_memory_mapping: 0000000000000000-000000007fffc000 Nov 14 08:52:17 rpmrepo01 kernel: RAMDISK: 36b7d000 - 37fefc6f Nov 14 08:52:17 rpmrepo01 kernel: ACPI: Deleted _OSI() Nov 14 08:52:17 rpmrepo01 kernel: ACPI: Deleted _OSI() Nov 14 08:52:17 rpmrepo01 kernel: ACPI: RSDP 00000000000fd8c0 00014 (v00 BOCHS ) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: RSDT 000000007fffcd50 00034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: FACP 000000007ffffb70 00074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: DSDT 000000007fffd650 024A2 (v01 BXPC BXDSDT 00000001 INTL 20090123) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: FACS 000000007ffffb00 00040 Nov 14 08:52:17 rpmrepo01 kernel: ACPI: SSDT 000000007fffd000 0064D (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: APIC 000000007fffcea0 000F0 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: SSDT 000000007fffcd90 0010F (v01 BXPC BXSSDTPC 00000001 INTL 20090123) Nov 14 08:52:17 rpmrepo01 kernel: Setting APIC routing to flat. Nov 14 08:52:17 rpmrepo01 kernel: No NUMA configuration found Nov 14 08:52:17 rpmrepo01 kernel: Faking a node at 0000000000000000-000000007fffc000 Nov 14 08:52:17 rpmrepo01 kernel: Bootmem setup node 0 0000000000000000-000000007fffc000 Nov 14 08:52:17 rpmrepo01 kernel: NODE_DATA [000000000000a000 - 000000000003dfff] Nov 14 08:52:17 rpmrepo01 kernel: bootmap [000000000003e000 - 000000000004dfff] pages 10 Nov 14 08:52:17 rpmrepo01 kernel: (7 early reservations) ==> bootmem [0000000000 - 007fffc000] Nov 14 08:52:17 rpmrepo01 kernel: #0 [0000000000 - 0000001000] BIOS data page ==> [0000000000 - 0000001000] Nov 14 08:52:17 rpmrepo01 kernel: #1 [0000006000 - 0000008000] TRAMPOLINE ==> [0000006000 - 0000008000] Nov 14 08:52:17 rpmrepo01 kernel: #2 [0001000000 - 0002044924] TEXT DATA BSS ==> [0001000000 - 0002044924] Nov 14 08:52:17 rpmrepo01 kernel: #3 [0036b7d000 - 0037fefc6f] RAMDISK ==> [0036b7d000 - 0037fefc6f] Nov 14 08:52:17 rpmrepo01 kernel: #4 [000009bc00 - 0000100000] BIOS reserved ==> [000009bc00 - 0000100000] Nov 14 08:52:17 rpmrepo01 kernel: #5 [0002045000 - 00020450d5] BRK ==> [0002045000 - 00020450d5] Nov 14 08:52:17 rpmrepo01 kernel: #6 [0000008000 - 000000a000] PGTABLE ==> [0000008000 - 000000a000] Nov 14 08:52:17 rpmrepo01 kernel: found SMP MP-table at [ffff8800000fd910] fd910 Nov 14 08:52:17 rpmrepo01 kernel: Reserving 129MB of memory at 48MB for crashkernel (System RAM: 2047MB) Nov 14 08:52:17 rpmrepo01 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Nov 14 08:52:17 rpmrepo01 kernel: kvm-clock: cpu 0, msr 0:1c38e41, boot clock Nov 14 08:52:17 rpmrepo01 kernel: Zone PFN ranges: Nov 14 08:52:17 rpmrepo01 kernel: DMA 0x00000001 -> 0x00001000 Nov 14 08:52:17 rpmrepo01 kernel: DMA32 0x00001000 -> 0x00100000 Nov 14 08:52:17 rpmrepo01 kernel: Normal 0x00100000 -> 0x00100000 Nov 14 08:52:17 rpmrepo01 kernel: Movable zone start PFN for each node Nov 14 08:52:17 rpmrepo01 kernel: early_node_map[2] active PFN ranges Nov 14 08:52:17 rpmrepo01 kernel: 0: 0x00000001 -> 0x0000009b Nov 14 08:52:17 rpmrepo01 kernel: 0: 0x00000100 -> 0x0007fffc Nov 14 08:52:17 rpmrepo01 kernel: ACPI: PM-Timer IO Port: 0xb008 Nov 14 08:52:17 rpmrepo01 kernel: Setting APIC routing to flat. Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x04] lapic_id[0x04] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x05] lapic_id[0x05] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x06] lapic_id[0x06] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x07] lapic_id[0x07] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x08] lapic_id[0x08] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x09] lapic_id[0x09] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x0a] lapic_id[0x0a] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x0b] lapic_id[0x0b] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x0c] lapic_id[0x0c] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x0d] lapic_id[0x0d] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x0e] lapic_id[0x0e] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC (acpi_id[0x0f] lapic_id[0x0f] disabled) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0]) Nov 14 08:52:17 rpmrepo01 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Nov 14 08:52:17 rpmrepo01 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Nov 14 08:52:17 rpmrepo01 kernel: Using ACPI (MADT) for SMP configuration information Nov 14 08:52:17 rpmrepo01 kernel: SMP: Allowing 16 CPUs, 15 hotplug CPUs Nov 14 08:52:17 rpmrepo01 kernel: PM: Registered nosave memory: 000000000009b000 - 000000000009c000 Nov 14 08:52:17 rpmrepo01 kernel: PM: Registered nosave memory: 000000000009c000 - 00000000000a0000 Nov 14 08:52:17 rpmrepo01 kernel: PM: Registered nosave memory: 00000000000a0000 - 00000000000f0000 Nov 14 08:52:17 rpmrepo01 kernel: PM: Registered nosave memory: 00000000000f0000 - 0000000000100000 Nov 14 08:52:17 rpmrepo01 kernel: Allocating PCI resources starting at 80000000 (gap: 80000000:7ffbc000) Nov 14 08:52:17 rpmrepo01 kernel: Booting paravirtualized kernel on KVM Nov 14 08:52:17 rpmrepo01 kernel: NR_CPUS:4096 nr_cpumask_bits:16 nr_cpu_ids:16 nr_node_ids:1 Nov 14 08:52:17 rpmrepo01 kernel: PERCPU: Embedded 31 pages/cpu @ffff880002200000 s96600 r8192 d22184 u131072 Nov 14 08:52:17 rpmrepo01 kernel: pcpu-alloc: s96600 r8192 d22184 u131072 alloc=12097152 Nov 14 08:52:17 rpmrepo01 kernel: pcpu-alloc: [0] 00 01 02 03 04 05 06 07 08 09 10 11 12 13 14 15 Nov 14 08:52:17 rpmrepo01 kernel: kvm-clock: cpu 0, msr 0:2216e41, primary cpu clock Nov 14 08:52:17 rpmrepo01 kernel: kvm-stealtime: cpu 0, msr 220ed00 Nov 14 08:52:17 rpmrepo01 kernel: Built 1 zonelists in Node order, mobility grouping on. Total pages: 516909 Nov 14 08:52:17 rpmrepo01 kernel: Policy zone: DMA32 Nov 14 08:52:17 rpmrepo01 kernel: Kernel command line: ro root=/dev/mapper/vg_rpmrepo01-lv_root rd_NO_LUKS LANG=en_US.UTF-8 rd_LVM_LV=vg_rpmrepo01/lv_root rd_NO_MD SYSFONT=latarcyrheb-sun16 crashkernel=129M@0M KEYBOARDTYPE=pc KEYTABLE=us rd_NO_DM rhgb quiet Nov 14 08:52:17 rpmrepo01 kernel: PID hash table entries: 4096 (order: 3, 32768 bytes) Nov 14 08:52:17 rpmrepo01 kernel: Memory: 1895844k/2097136k available (5460k kernel code, 408k absent, 200884k reserved, 6959k data, 1316k init) Nov 14 08:52:17 rpmrepo01 kernel: Hierarchical RCU implementation. Nov 14 08:52:17 rpmrepo01 kernel: NR_IRQS:33024 nr_irqs:536 Nov 14 08:52:17 rpmrepo01 kernel: Console: colour VGA+ 80x25 Nov 14 08:52:17 rpmrepo01 kernel: console [tty0] enabled Nov 14 08:52:17 rpmrepo01 kernel: allocated 8388608 bytes of page_cgroup Nov 14 08:52:17 rpmrepo01 kernel: please try 'cgroup_disable=memory' option if you don't want memory cgroups Nov 14 08:52:17 rpmrepo01 kernel: Detected 2399.316 MHz processor. Nov 14 08:52:17 rpmrepo01 kernel: Calibrating delay loop (skipped) preset value.. 4798.63 BogoMIPS (lpj=2399316) Nov 14 08:52:17 rpmrepo01 kernel: pid_max: default: 32768 minimum: 301 Nov 14 08:52:17 rpmrepo01 kernel: Security Framework initialized Nov 14 08:52:17 rpmrepo01 kernel: SELinux: Initializing. Nov 14 08:52:17 rpmrepo01 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) Nov 14 08:52:17 rpmrepo01 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) Nov 14 08:52:17 rpmrepo01 kernel: Mount-cache hash table entries: 256 Nov 14 08:52:17 rpmrepo01 kernel: Initializing cgroup subsys ns Nov 14 08:52:17 rpmrepo01 kernel: Initializing cgroup subsys cpuacct Nov 14 08:52:17 rpmrepo01 kernel: Initializing cgroup subsys memory Nov 14 08:52:17 rpmrepo01 kernel: Initializing cgroup subsys devices Nov 14 08:52:17 rpmrepo01 kernel: Initializing cgroup subsys freezer Nov 14 08:52:17 rpmrepo01 kernel: Initializing cgroup subsys net_cls Nov 14 08:52:17 rpmrepo01 kernel: Initializing cgroup subsys blkio Nov 14 08:52:17 rpmrepo01 kernel: Initializing cgroup subsys perf_event Nov 14 08:52:17 rpmrepo01 kernel: Initializing cgroup subsys net_prio Nov 14 08:52:17 rpmrepo01 kernel: Disabled fast string operations Nov 14 08:52:17 rpmrepo01 kernel: mce: CPU supports 10 MCE banks Nov 14 08:52:17 rpmrepo01 kernel: alternatives: switching to unfair spinlock Nov 14 08:52:17 rpmrepo01 kernel: SMP alternatives: switching to UP code Nov 14 08:52:17 rpmrepo01 kernel: ACPI: Core revision 20090903 Nov 14 08:52:17 rpmrepo01 kernel: ftrace: converting mcount calls to 0f 1f 44 00 00 Nov 14 08:52:17 rpmrepo01 kernel: ftrace: allocating 22343 entries in 88 pages Nov 14 08:52:17 rpmrepo01 kernel: Enabling x2apic Nov 14 08:52:17 rpmrepo01 kernel: Enabled x2apic Nov 14 08:52:17 rpmrepo01 kernel: APIC routing finalized to physical x2apic. Nov 14 08:52:17 rpmrepo01 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Nov 14 08:52:17 rpmrepo01 kernel: CPU0: Intel Westmere E56xx/L56xx/X56xx (Nehalem-C) stepping 01 Nov 14 08:52:17 rpmrepo01 kernel: Performance Events: unsupported p6 CPU model 44 no PMU driver, software events only. Nov 14 08:52:17 rpmrepo01 kernel: NMI watchdog disabled (cpu0): hardware events not enabled Nov 14 08:52:17 rpmrepo01 kernel: Brought up 1 CPUs Nov 14 08:52:17 rpmrepo01 kernel: Total of 1 processors activated (4798.63 BogoMIPS). Nov 14 08:52:17 rpmrepo01 kernel: devtmpfs: initialized Nov 14 08:52:17 rpmrepo01 kernel: regulator: core version 0.5 Nov 14 08:52:17 rpmrepo01 kernel: NET: Registered protocol family 16 Nov 14 08:52:17 rpmrepo01 kernel: ACPI: bus type pci registered Nov 14 08:52:17 rpmrepo01 kernel: PCI: Using configuration type 1 for base access Nov 14 08:52:17 rpmrepo01 kernel: bio: create slab at 0 Nov 14 08:52:17 rpmrepo01 kernel: ACPI: Interpreter enabled Nov 14 08:52:17 rpmrepo01 kernel: ACPI: (supports S0 S5) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: Using IOAPIC for interrupt routing Nov 14 08:52:17 rpmrepo01 kernel: ACPI: No dock devices found. Nov 14 08:52:17 rpmrepo01 kernel: PCI: Ignoring host bridge windows from ACPI; if necessary, use "pci=use_crs" and report a bug Nov 14 08:52:17 rpmrepo01 kernel: ACPI: PCI Root Bridge [PCI0](domain 0000 [bus 00-ff]) Nov 14 08:52:17 rpmrepo01 kernel: PCI host bridge to bus 0000:00 Nov 14 08:52:17 rpmrepo01 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff] Nov 14 08:52:17 rpmrepo01 kernel: pci_bus 0000:00: root bus resource [mem 0x00000000-0xffffffffff] Nov 14 08:52:17 rpmrepo01 kernel: pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI Nov 14 08:52:17 rpmrepo01 kernel: pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB Nov 14 08:52:17 rpmrepo01 kernel: ACPI: PCI Interrupt Link [LNKA](IRQs 5 10 11) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: PCI Interrupt Link [LNKB](IRQs 5 10 11) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: PCI Interrupt Link [LNKC](IRQs 5 10 11) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: PCI Interrupt Link [LNKD](IRQs 5 10 11) Nov 14 08:52:17 rpmrepo01 kernel: ACPI: PCI Interrupt Link [LNKS](IRQs 9) Nov 14 08:52:17 rpmrepo01 kernel: vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none Nov 14 08:52:17 rpmrepo01 kernel: vgaarb: loaded Nov 14 08:52:17 rpmrepo01 kernel: vgaarb: bridge control possible 0000:00:02.0 Nov 14 08:52:17 rpmrepo01 kernel: SCSI subsystem initialized Nov 14 08:52:17 rpmrepo01 kernel: usbcore: registered new interface driver usbfs Nov 14 08:52:17 rpmrepo01 kernel: usbcore: registered new interface driver hub Nov 14 08:52:17 rpmrepo01 kernel: usbcore: registered new device driver usb Nov 14 08:52:17 rpmrepo01 kernel: PCI: Using ACPI for IRQ routing Nov 14 08:52:17 rpmrepo01 kernel: NetLabel: Initializing Nov 14 08:52:17 rpmrepo01 kernel: NetLabel: domain hash size = 128 Nov 14 08:52:17 rpmrepo01 kernel: NetLabel: protocols = UNLABELED CIPSOv4 Nov 14 08:52:17 rpmrepo01 kernel: NetLabel: unlabeled traffic allowed by default Nov 14 08:52:17 rpmrepo01 kernel: Switching to clocksource kvm-clock Nov 14 08:52:17 rpmrepo01 kernel: pnp: PnP ACPI init Nov 14 08:52:17 rpmrepo01 kernel: ACPI: bus type pnp registered Nov 14 08:52:17 rpmrepo01 kernel: pnp: PnP ACPI: found 5 devices Nov 14 08:52:17 rpmrepo01 kernel: ACPI: ACPI bus type pnp unregistered Nov 14 08:52:17 rpmrepo01 kernel: NET: Registered protocol family 2 Nov 14 08:52:17 rpmrepo01 kernel: IP route cache hash table entries: 65536 (order: 7, 524288 bytes) Nov 14 08:52:17 rpmrepo01 kernel: TCP established hash table entries: 262144 (order: 10, 4194304 bytes) Nov 14 08:52:17 rpmrepo01 kernel: TCP bind hash table entries: 65536 (order: 8, 1048576 bytes) Nov 14 08:52:17 rpmrepo01 kernel: TCP: Hash tables configured (established 262144 bind 65536) Nov 14 08:52:17 rpmrepo01 kernel: TCP reno registered Nov 14 08:52:17 rpmrepo01 kernel: NET: Registered protocol family 1 Nov 14 08:52:17 rpmrepo01 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Nov 14 08:52:17 rpmrepo01 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Nov 14 08:52:17 rpmrepo01 kernel: pci 0000:00:01.0: Activating ISA DMA hang workarounds Nov 14 08:52:17 rpmrepo01 kernel: ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11 Nov 14 08:52:17 rpmrepo01 kernel: pci 0000:00:01.2: PCI INT D -> Link[LNKD] -> GSI 11 (level, high) -> IRQ 11 Nov 14 08:52:17 rpmrepo01 kernel: pci 0000:00:01.2: PCI INT D disabled Nov 14 08:52:17 rpmrepo01 kernel: Trying to unpack rootfs image as initramfs... Nov 14 08:52:17 rpmrepo01 kernel: Freeing initrd memory: 20939k freed Nov 14 08:52:17 rpmrepo01 kernel: sha256_ssse3: Using SSSE3 optimized SHA-256 implementation Nov 14 08:52:17 rpmrepo01 kernel: futex hash table entries: 4096 (order: 6, 262144 bytes) Nov 14 08:52:17 rpmrepo01 kernel: audit: initializing netlink socket (disabled) Nov 14 08:52:17 rpmrepo01 kernel: type=2000 audit(1479109860.559:1): initialized Nov 14 08:52:17 rpmrepo01 kernel: HugeTLB registered 2 MB page size, pre-allocated 0 pages Nov 14 08:52:17 rpmrepo01 kernel: VFS: Disk quotas dquot_6.5.2 Nov 14 08:52:17 rpmrepo01 kernel: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 14 08:52:17 rpmrepo01 kernel: msgmni has been set to 3743 Nov 14 08:52:17 rpmrepo01 kernel: ksign: Installing public key data Nov 14 08:52:17 rpmrepo01 kernel: Loading keyring Nov 14 08:52:17 rpmrepo01 kernel: - Added public key 78F4CA0B3767D7C Nov 14 08:52:17 rpmrepo01 kernel: - User ID: CentOS (Kernel Module GPG key) Nov 14 08:52:17 rpmrepo01 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Nov 14 08:52:17 rpmrepo01 kernel: io scheduler noop registered Nov 14 08:52:17 rpmrepo01 kernel: io scheduler anticipatory registered Nov 14 08:52:17 rpmrepo01 kernel: io scheduler deadline registered Nov 14 08:52:17 rpmrepo01 kernel: io scheduler cfq registered (default) Nov 14 08:52:17 rpmrepo01 kernel: pci_hotplug: PCI Hot Plug PCI Core version: 0.5 Nov 14 08:52:17 rpmrepo01 kernel: pciehp: PCI Express Hot Plug Controller Driver version: 0.4 Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [1] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [2] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [3] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [4] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [5] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [6] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [7] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [8] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [9] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [10] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [11] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [12] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [13] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [14] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [15] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [16] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [17] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [18] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [19] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [20] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [21] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [22] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [23] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [24] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [25] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [26] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [27] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [28] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [29] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [30] registered Nov 14 08:52:17 rpmrepo01 kernel: acpiphp: Slot [31] registered Nov 14 08:52:17 rpmrepo01 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 Nov 14 08:52:17 rpmrepo01 kernel: ACPI: Power Button [PWRF] Nov 14 08:52:17 rpmrepo01 kernel: [Firmware Bug]: No valid trip found Nov 14 08:52:17 rpmrepo01 kernel: GHES: HEST is not enabled! Nov 14 08:52:17 rpmrepo01 kernel: Non-volatile memory driver v1.3 Nov 14 08:52:17 rpmrepo01 kernel: Linux agpgart interface v0.103 Nov 14 08:52:17 rpmrepo01 kernel: crash memory driver: version 1.1 Nov 14 08:52:17 rpmrepo01 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 14 08:52:17 rpmrepo01 kernel: brd: module loaded Nov 14 08:52:17 rpmrepo01 kernel: loop: module loaded Nov 14 08:52:17 rpmrepo01 kernel: input: Macintosh mouse button emulation as /devices/virtual/input/input1 Nov 14 08:52:17 rpmrepo01 kernel: Fixed MDIO Bus: probed Nov 14 08:52:17 rpmrepo01 kernel: ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver Nov 14 08:52:17 rpmrepo01 kernel: ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver Nov 14 08:52:17 rpmrepo01 kernel: uhci_hcd: USB Universal Host Controller Interface driver Nov 14 08:52:17 rpmrepo01 kernel: uhci_hcd 0000:00:01.2: PCI INT D -> Link[LNKD] -> GSI 11 (level, high) -> IRQ 11 Nov 14 08:52:17 rpmrepo01 kernel: uhci_hcd 0000:00:01.2: UHCI Host Controller Nov 14 08:52:17 rpmrepo01 kernel: uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 Nov 14 08:52:17 rpmrepo01 kernel: uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c020 Nov 14 08:52:17 rpmrepo01 kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0001 Nov 14 08:52:17 rpmrepo01 kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 Nov 14 08:52:17 rpmrepo01 kernel: usb usb1: Product: UHCI Host Controller Nov 14 08:52:17 rpmrepo01 kernel: usb usb1: Manufacturer: Linux 2.6.32-642.6.2.el6.x86_64 uhci_hcd Nov 14 08:52:17 rpmrepo01 kernel: usb usb1: SerialNumber: 0000:00:01.2 Nov 14 08:52:17 rpmrepo01 kernel: usb usb1: configuration #1 chosen from 1 choice Nov 14 08:52:17 rpmrepo01 kernel: hub 1-0:1.0: USB hub found Nov 14 08:52:17 rpmrepo01 kernel: hub 1-0:1.0: 2 ports detected Nov 14 08:52:17 rpmrepo01 kernel: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Nov 14 08:52:17 rpmrepo01 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Nov 14 08:52:17 rpmrepo01 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Nov 14 08:52:17 rpmrepo01 kernel: mice: PS/2 mouse device common for all mice Nov 14 08:52:17 rpmrepo01 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input2 Nov 14 08:52:17 rpmrepo01 kernel: rtc_cmos 00:01: RTC can wake from S4 Nov 14 08:52:17 rpmrepo01 kernel: rtc_cmos 00:01: rtc core: registered rtc_cmos as rtc0 Nov 14 08:52:17 rpmrepo01 kernel: rtc0: alarms up to one day, 114 bytes nvram Nov 14 08:52:17 rpmrepo01 kernel: cpuidle: using governor ladder Nov 14 08:52:17 rpmrepo01 kernel: cpuidle: using governor menu Nov 14 08:52:17 rpmrepo01 kernel: EFI Variables Facility v0.08 2004-May-17 Nov 14 08:52:17 rpmrepo01 kernel: usbcore: registered new interface driver hiddev Nov 14 08:52:17 rpmrepo01 kernel: usbcore: registered new interface driver usbhid Nov 14 08:52:17 rpmrepo01 kernel: usbhid: v2.6:USB HID core driver Nov 14 08:52:17 rpmrepo01 kernel: GRE over IPv4 demultiplexor driver Nov 14 08:52:17 rpmrepo01 kernel: TCP cubic registered Nov 14 08:52:17 rpmrepo01 kernel: Initializing XFRM netlink socket Nov 14 08:52:17 rpmrepo01 kernel: NET: Registered protocol family 17 Nov 14 08:52:17 rpmrepo01 kernel: registered taskstats version 1 Nov 14 08:52:17 rpmrepo01 kernel: rtc_cmos 00:01: setting system clock to 2016-11-14 07:50:59 UTC (1479109859) Nov 14 08:52:17 rpmrepo01 kernel: Initalizing network drop monitor service Nov 14 08:52:17 rpmrepo01 kernel: Freeing unused kernel memory: 1316k freed Nov 14 08:52:17 rpmrepo01 kernel: Write protecting the kernel read-only data: 10240k Nov 14 08:52:17 rpmrepo01 kernel: Freeing unused kernel memory: 664k freed Nov 14 08:52:17 rpmrepo01 kernel: Freeing unused kernel memory: 1556k freed Nov 14 08:52:17 rpmrepo01 kernel: dracut: dracut-004-409.el6_8.2 Nov 14 08:52:17 rpmrepo01 kernel: dracut: rd_NO_LUKS: removing cryptoluks activation Nov 14 08:52:17 rpmrepo01 kernel: device-mapper: uevent: version 1.0.3 Nov 14 08:52:17 rpmrepo01 kernel: device-mapper: ioctl: 4.33.1-ioctl (2015-8-18) initialised: dm-devel@redhat.com Nov 14 08:52:17 rpmrepo01 kernel: udev: starting version 147 Nov 14 08:52:17 rpmrepo01 kernel: dracut: Starting plymouth daemon Nov 14 08:52:17 rpmrepo01 kernel: dracut: rd_NO_MD: removing MD RAID activation Nov 14 08:52:17 rpmrepo01 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Nov 14 08:52:17 rpmrepo01 kernel: scsi0 : ata_piix Nov 14 08:52:17 rpmrepo01 kernel: scsi1 : ata_piix Nov 14 08:52:17 rpmrepo01 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc000 irq 14 Nov 14 08:52:17 rpmrepo01 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc008 irq 15 Nov 14 08:52:17 rpmrepo01 kernel: ata2.00: ATAPI: QEMU DVD-ROM, 0.12.1, max UDMA/100 Nov 14 08:52:17 rpmrepo01 kernel: ata2.00: configured for MWDMA2 Nov 14 08:52:17 rpmrepo01 kernel: scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 0.12 PQ: 0 ANSI: 5 Nov 14 08:52:17 rpmrepo01 kernel: ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10 Nov 14 08:52:17 rpmrepo01 kernel: virtio-pci 0000:00:03.0: PCI INT A -> Link[LNKC] -> GSI 10 (level, high) -> IRQ 10 Nov 14 08:52:17 rpmrepo01 kernel: virtio-pci 0000:00:04.0: PCI INT A -> Link[LNKD] -> GSI 11 (level, high) -> IRQ 11 Nov 14 08:52:17 rpmrepo01 kernel: ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10 Nov 14 08:52:17 rpmrepo01 kernel: virtio-pci 0000:00:05.0: PCI INT A -> Link[LNKA] -> GSI 10 (level, high) -> IRQ 10 Nov 14 08:52:17 rpmrepo01 kernel: ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11 Nov 14 08:52:17 rpmrepo01 kernel: virtio-pci 0000:00:06.0: PCI INT A -> Link[LNKB] -> GSI 11 (level, high) -> IRQ 11 Nov 14 08:52:17 rpmrepo01 kernel: Refined TSC clocksource calibration: 2399.342 MHz. Nov 14 08:52:17 rpmrepo01 kernel: sr0: scsi3-mmc drive: 4x/4x xa/form2 tray Nov 14 08:52:17 rpmrepo01 kernel: Uniform CD-ROM driver Revision: 3.20 Nov 14 08:52:17 rpmrepo01 kernel: vda: vda1 Nov 14 08:52:17 rpmrepo01 kernel: vdb: vdb1 vdb2 Nov 14 08:52:17 rpmrepo01 kernel: dracut: Scanning devices vdb2 for LVM logical volumes vg_rpmrepo01/lv_root Nov 14 08:52:17 rpmrepo01 kernel: dracut: inactive '/dev/vg_rpmrepo01/lv_root' [5.86 GiB] inherit Nov 14 08:52:17 rpmrepo01 kernel: dracut: inactive '/dev/vg_rpmrepo01/lv_var' [38.94 GiB] inherit Nov 14 08:52:17 rpmrepo01 kernel: EXT4-fs (dm-0): INFO: recovery required on readonly filesystem Nov 14 08:52:17 rpmrepo01 kernel: EXT4-fs (dm-0): write access will be enabled during recovery Nov 14 08:52:17 rpmrepo01 kernel: EXT4-fs (dm-0): recovery complete Nov 14 08:52:17 rpmrepo01 kernel: EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: Nov 14 08:52:17 rpmrepo01 kernel: dracut: Mounted root filesystem /dev/mapper/vg_rpmrepo01-lv_root Nov 14 08:52:17 rpmrepo01 kernel: dracut: Loading SELinux policy Nov 14 08:52:17 rpmrepo01 kernel: type=1403 audit(1479109865.944:2): policy loaded auid=4294967295 ses=4294967295 Nov 14 08:52:17 rpmrepo01 kernel: dracut: Nov 14 08:52:17 rpmrepo01 kernel: dracut: Switching root Nov 14 08:52:17 rpmrepo01 kernel: udev: starting version 147 Nov 14 08:52:17 rpmrepo01 kernel: sr 1:0:0:0: Attached scsi generic sg0 type 5 Nov 14 08:52:17 rpmrepo01 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0 Nov 14 08:52:17 rpmrepo01 kernel: EXT4-fs (dm-1): mounted filesystem with ordered data mode. Opts: Nov 14 08:52:17 rpmrepo01 kernel: NET: Registered protocol family 10 Nov 14 08:52:17 rpmrepo01 kernel: lo: Disabled Privacy Extensions Nov 14 08:52:17 rpmrepo01 kernel: ip6_tables: (C) 2000-2006 Netfilter Core Team Nov 14 08:52:17 rpmrepo01 kernel: nf_conntrack version 0.5.0 (16384 buckets, 65536 max) Nov 14 08:52:17 rpmrepo01 kernel: type=1305 audit(1479109935.467:3): audit_pid=1385 old=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:auditd_t:s0 res=1 Nov 14 08:52:20 rpmrepo01 sssd: Starting up Nov 14 08:52:21 rpmrepo01 sssd[be[ad.nieuwland.nl]]: Starting up Nov 14 08:52:21 rpmrepo01 sssd[nss]: Starting up Nov 14 08:52:21 rpmrepo01 sssd[pam]: Starting up Nov 14 08:52:22 rpmrepo01 rpc.statd[1477]: Version 1.2.3 starting Nov 14 08:52:22 rpmrepo01 sm-notify[1478]: Version 1.2.3 starting Nov 14 08:52:32 rpmrepo01 snmpd[1653]: NET-SNMP version 5.5 Nov 14 08:52:33 rpmrepo01 ntpd[1679]: ntpd 4.2.6p5@1.2349-o Tue May 31 10:09:21 UTC 2016 (1) Nov 14 08:52:33 rpmrepo01 ntpd[1680]: proto: precision = 0.307 usec Nov 14 08:52:33 rpmrepo01 ntpd[1680]: 0.0.0.0 c01d 0d kern kernel time sync enabled Nov 14 08:52:33 rpmrepo01 ntpd[1680]: Listen and drop on 0 v4wildcard 0.0.0.0 UDP 123 Nov 14 08:52:33 rpmrepo01 ntpd[1680]: Listen and drop on 1 v6wildcard :: UDP 123 Nov 14 08:52:33 rpmrepo01 ntpd[1680]: Listen normally on 2 lo 127.0.0.1 UDP 123 Nov 14 08:52:33 rpmrepo01 ntpd[1680]: Listen normally on 3 eth0 172.19.1.32 UDP 123 Nov 14 08:52:33 rpmrepo01 ntpd[1680]: Listen normally on 4 lo ::1 UDP 123 Nov 14 08:52:33 rpmrepo01 ntpd[1680]: Listen normally on 5 eth0 fe80::21a:4aff:fe10:1049 UDP 123 Nov 14 08:52:33 rpmrepo01 ntpd[1680]: Listening on routing socket on fd #22 for interface updates Nov 14 08:52:34 rpmrepo01 nrpe[1690]: Unable to open config file '/etc/nagios/nrpe_local.cfg' for reading Nov 14 08:52:34 rpmrepo01 nrpe[1690]: Continuing with errors... Nov 14 08:52:34 rpmrepo01 nrpe[1691]: Starting up daemon Nov 14 08:52:34 rpmrepo01 nrpe[1691]: Server listening on 0.0.0.0 port 5666. Nov 14 08:52:34 rpmrepo01 nrpe[1691]: Server listening on :: port 5666. Nov 14 08:52:34 rpmrepo01 nrpe[1691]: Warning: Daemon is configured to accept command arguments from clients! Nov 14 08:52:34 rpmrepo01 nrpe[1691]: Listening for connections on port 0 Nov 14 08:52:34 rpmrepo01 nrpe[1691]: Allowing connections from: 172.16.16.117,172.16.16.118 Nov 14 08:52:35 rpmrepo01 ntpd[1680]: 0.0.0.0 c016 06 restart Nov 14 08:52:35 rpmrepo01 ntpd[1680]: 0.0.0.0 c012 02 freq_set kernel -0.525 PPM Nov 14 08:52:36 rpmrepo01 ntpd[1680]: 0.0.0.0 c61c 0c clock_step +1.398575 s Nov 14 08:52:36 rpmrepo01 ntpd[1680]: 0.0.0.0 c614 04 freq_mode Nov 14 08:52:37 rpmrepo01 ntpd[1680]: 0.0.0.0 c618 08 no_sys_peer Nov 14 08:53:11 rpmrepo01 puppet-agent[1988]: Reopening log files Nov 14 08:53:12 rpmrepo01 puppet-agent[1988]: Starting Puppet client version 3.7.4

JvdW commented 8 years ago

On 13-11-2016 16:00, Petros Koutoupis wrote:

Is there a stack dump on tty1 or in dmesg?

So, right after I send the last message I had an idea. What if its related to how the disk is presented to the VM. There are 2 way and the default is VirtIO and gives you a /dev/vda, changing that to VirtIO-SCSI gives you a /dev/sda. I just did that and no problems :-) Look like the driver implementation of VirtIO is 'buggy', bad news is that this is the default and I don't know if and what differences there are between VirtIO and Virtio-SCSI. Will ask around in IRC and the ovirt forum for that.

Joop

pkoutoupis commented 8 years ago

Thank you very much for all of this and for your initial investigation. Note, there is nothing different being done in the RapidDisk code for SCSI devices. The code is designed to work with all block devices (loop, NVMe, etc.). I will need to recreate your setup locally over here, to capture a kdump (for crash debugging). But in the meanwhile, can you see if there is a difference between the following:

  1. Using Ext4 instead of XFS
  2. Running fio on the /dev/vda volume without RapidDisk/RapidDisk-Cache
  3. Also, what is the output of the VMs /proc/partitions? I want to make sure that cache mapping size is identical to the /dev/vda size.

Thank you again.

JvdW commented 8 years ago

On 14-11-2016 14:54, Petros Koutoupis wrote:

Thank you very much for all of this and for your initial investigation. Note, there is nothing different being done in the RapidDisk code for SCSI devices. The code is designed to work with all block devices (loop, NVMe, etc.). I will need to recreate your setup locally over here, to capture a kdump (for crash debugging). But in the meanwhile, can you see if there is a difference between the following:

  1. Using Ext4 instead of XFS

Same. root@rpmrepo01:/ # ll /mnt/rc_vdb1/ total 1536020 drwx------. 2 root root 16384 Nov 14 15:59 lost+found -rw-r--r--. 1 root root 1572864000 Nov 14 16:14 ssd.test.file root@rpmrepo01:/ # fio /usr/share/doc/fio-2.0.13/examples/ssd-test seq-read: (g=0): rw=read, bs=4K-4K/4K-4K/4K-4K, ioengine=libaio, iodepth=4 rand-read: (g=1): rw=randread, bs=4K-4K/4K-4K/4K-4K, ioengine=libaio, iodepth=4 seq-write: (g=2): rw=write, bs=4K-4K/4K-4K/4K-4K, ioengine=libaio, iodepth=4 rand-write: (g=3): rw=randwrite, bs=4K-4K/4K-4K/4K-4K, ioengine=libaio, iodepth=4 fio-2.0.13 Starting 4 processes

  1. Running fio on the /dev/vda volume without RapidDisk/RapidDisk-Cache

No problem running fio in disks which are either vda or sda without rapiddisk caching.

  1. Also, what is the output of the VMs /proc/partitions? I want to make sure that cache mapping size is identical to the /dev/vda size.

    252 0 47185920 vda 252 1 204800 vda1 252 2 46980096 vda2 8 0 5242880 sda 8 1 5241856 sda1 253 0 6144000 dm-0 253 1 40833024 dm-1 253 2 5241856 dm-2 252 16 2097152 vdb 252 17 2096128 vdb1 253 3 2096128 dm-3

root@rpmrepo01:~ # fdisk -luc /dev/vdb

Disk /dev/vdb: 2147 MB, 2147483648 bytes 1 heads, 16 sectors/track, 262144 cylinders, total 4194304 sectors Units = sectors of 1 * 512 = 512 bytes Sector size (logical/physical): 512 bytes / 512 bytes I/O size (minimum/optimal): 512 bytes / 512 bytes Disk identifier: 0xc5e829de

Device Boot Start End Blocks Id System /dev/vdb1 2048 4194303 2096128 83 Linux

Added a test disk of 2G to the VM using VirtIO so --> /dev/vdb fdisk -uc /dev/vdb (create 1 primary partition spanning sector 2048 til end) mkfs.ext4 /dev/vdb1 rapiddisk --attach 64 rapiddisk --cache-map rd1 /dev/vdb1 mount /dev/mapper/rc-wt_vdb1 /mnt/rc-vdb1 run fio and it will make the VM unresponsive en crash it without leaving a trace when restarting it :-(

Now I changed the disk from VirtIO to VirtIO-SCSI --> /dev/sdb rapiddisk --attach 64 rapiddisk --cache-map rd0 /dev/sdb1 mount /dev/mapper/rc-wt_sdb1 /mnt/rc-vdb1 run fio and no problems.

Part of the output from virsh -r dumpxml rpmrepo01 so that you can see whats passed to libvirt:

<disk type='file' device='disk' snapshot='no'>
  <driver name='qemu' type='raw' cache='none' error_policy='stop' io='threads'/>
  <source file='/rhev/data-center/mnt/nfs01.stor.nieuwland.nl:_export_ovirtproductie/fbaffdc6-9d4d-454f-975b-9516faf927c6/images/0450f5f3-44fa-46ea-8bd6-9b820d0208bf/0addfcf7-8867-45c2-bd07-aa35afe64b85'>
    <seclabel model='selinux' relabel='no'/>
  </source>
  <target dev='vda' bus='virtio'/>
  <serial>0450f5f3-44fa-46ea-8bd6-9b820d0208bf</serial>
  <boot order='1'/>
  <alias name='virtio-disk0'/>
  <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x0'/>
</disk>
<disk type='file' device='disk' snapshot='no'>
  <driver name='qemu' type='raw' cache='none' error_policy='stop' io='threads'/>
  <source file='/rhev/data-center/mnt/nfs02.nieuwland.nl:_nfs_lv__ovirt__infradata/7f97cf40-7733-40f0-bfaf-5f017f36acac/images/3d3e5285-230a-4843-bf55-c3cd85f7297a/5ecc4d0d-d78d-4414-b71a-c715910fa58d'>
    <seclabel model='selinux' relabel='no'/>
  </source>
  <target dev='sda' bus='scsi'/>
  <serial>3d3e5285-230a-4843-bf55-c3cd85f7297a</serial>
  <alias name='scsi0-0-0-0'/>
  <address type='drive' controller='0' bus='0' target='0' unit='0'/>
</disk>
<controller type='scsi' index='0' model='virtio-scsi'>
  <alias name='scsi0'/>
  <address type='pci' domain='0x0000' bus='0x00' slot='0x04' function='0x0'/>
</controller>
<controller type='usb' index='0'>
  <alias name='usb0'/>
  <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x2'/>
</controller>
<controller type='ide' index='0'>
  <alias name='ide0'/>
  <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x1'/>
</controller>
<controller type='virtio-serial' index='0'>
  <alias name='virtio-serial0'/>
  <address type='pci' domain='0x0000' bus='0x00' slot='0x05' function='0x0'/>
</controller>

The arguments passed to qemu by oVirt: qemu 4653 1 20 16:18 ? 00:03:10 /usr/libexec/qemu-kvm -name rpmrepo01 -S -M rhel6.5.0 -cpu Westmere -enable-kvm -m 2048 -realtime mlock=off -smp 1,maxcpus=16,sockets=16,cores=1,threads=1 -uuid 69477ca3-1d45-4c5a-9d6e-0eeb4ff52286 -smbios type=1,manufacturer=oVirt,product=oVirt Node,version=6-5.el6.centos.11.2,serial=34333336-3730-5A43-3232-3038304E3452,uuid=69477ca3-1d45-4c5a-9d6e-0eeb4ff52286 -nodefconfig -nodefaults -chardev socket,id=charmonitor,path=/var/lib/libvirt/qemu/rpmrepo01.monitor,server,nowait -mon chardev=charmonitor,id=monitor,mode=control -rtc base=2016-11-14T15:18:28,driftfix=slew -no-shutdown -device piix3-usb-uhci,id=usb,bus=pci.0,addr=0x1.0x2 -device virtio-scsi-pci,id=scsi0,bus=pci.0,addr=0x4 -device virtio-serial-pci,id=virtio-serial0,bus=pci.0,addr=0x5 -drive if=none,media=cdrom,id=drive-ide0-1-0,readonly=on,format=raw,serial= -device ide-drive,bus=ide.1,unit=0,drive=drive-ide0-1-0,id=ide0-1-0 -drive file=/rhev/data-center/mnt/nfs01.stor.nieuwland.nl:_export_ovirtproductie/fbaffdc6-9d4d-454f-975b-9516faf927c6/images/0450f5f3-44fa-46ea-8bd6-9b820d0208bf/0addfcf7-8867-45c2-bd07-aa35afe64b85,if=none,id=drive-virtio-disk0,format=raw,serial=0450f5f3-44fa-46ea-8bd6-9b820d0208bf,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x6,drive=drive-virtio-disk0,id=virtio-disk0,bootindex=1 -drive file=/rhev/data-center/mnt/nfs01.stor.nieuwland.nl:_export_ovirtproductie/fbaffdc6-9d4d-454f-975b-9516faf927c6/images/ba96e053-bac8-4b31-af40-8d9105dfc053/4aa4682c-82da-4fe1-a44e-0205b05ae78e,if=none,id=drive-virtio-disk1,format=raw,serial=ba96e053-bac8-4b31-af40-8d9105dfc053,cache=none,werror=stop,rerror=stop,aio=threads -device virtio-blk-pci,scsi=off,bus=pci.0,addr=0x7,drive=drive-virtio-disk1,id=virtio-disk1 -drive file=/rhev/data-center/mnt/nfs02.nieuwland.nl:_nfs_lvovirtinfradata/7f97cf40-7733-40f0-bfaf-5f017f36acac/images/3d3e5285-230a-4843-bf55-c3cd85f7297a/5ecc4d0d-d78d-4414-b71a-c715910fa58d,if=none,id=drive-scsi0-0-0-0,format=raw,serial=3d3e5285-230a-4843-bf55-c3cd85f7297a,cache=none,werror=stop,rerror=stop,aio=threads -device scsi-hd,bus=scsi0.0,channel=0,scsi-id=0,lun=0,drive=drive-scsi0-0-0-0,id=scsi0-0-0-0 -netdev tap,fd=34,id=hostnet0,vhost=on,vhostfd=39 -device virtio-net-pci,netdev=hostnet0,id=net0,mac=00:1a:4a:10:10:49,bus=pci.0,addr=0x3 -chardev socket,id=charchannel0,path=/var/lib/libvirt/qemu/channels/69477ca3-1d45-4c5a-9d6e-0eeb4ff52286.com.redhat.rhevm.vdsm,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=1,chardev=charchannel0,id=channel0,name=com.redhat.rhevm.vdsm -chardev socket,id=charchannel1,path=/var/lib/libvirt/qemu/channels/69477ca3-1d45-4c5a-9d6e-0eeb4ff52286.org.qemu.guest_agent.0,server,nowait -device virtserialport,bus=virtio-serial0.0,nr=2,chardev=charchannel1,id=channel1,name=org.qemu.guest_agent.0 -chardev spicevmc,id=charchannel2,name=vdagent -device virtserialport,bus=virtio-serial0.0,nr=3,chardev=charchannel2,id=channel2,name=com.redhat.spice.0 -spice port=5911,tls-port=5914,addr=0,x509-dir=/etc/pki/vdsm/libvirt-spice,tls-channel=main,tls-channel=display,tls-channel=inputs,tls-channel=cursor,tls-channel=playback,tls-channel=record,tls-channel=smartcard,tls-channel=usbredir,seamless-migration=on -k en-us -vga qxl -global qxl-vga.ram_size=67108864 -global qxl-vga.vram_size=33554432

Joop

pkoutoupis commented 8 years ago

Thank you for all of this. Again, very soon I will stand up a VM and attempt to reproduce this. I have one additional request: can you rerun the test with a dm-linear mapping on top of the vda device? http://tldp.org/HOWTO/LVM-HOWTO/createlv.html

Based on the stack dump you provided above, it would seem that the part showcasing the problem is in the asynchronous callback handler of rapiddisk-cache. Now, with dm-linear, all I/O operations are synchronous and I want to rule out general device mapper operation.

JvdW commented 8 years ago

On 14-11-2016 22:27, Petros Koutoupis wrote:

Thank you for all of this. Again, very soon I will stand up a VM and attempt to reproduce this. I have one additional request: can you rerun the test with a dm-linear mapping on top of the vda device? http://tldp.org/HOWTO/LVM-HOWTO/createlv.html

Based on the stack dump you provided above, it would seem that the part showcasing the problem is in the asynchronous callback handler of rapiddisk-cache. Now, with dm-linear, all I/O operations are synchronous and I want to rule out general device mapper operation.

Done as asked but the same problem, after starting the test it will make the VM unresponsive and after about 20-30 secs it will disconnect and then I see 100% CPU usage in oVirt and I can only hard poweroff the VM.

Log of what I did is attached.

Joop

root@rpmrepo01:/ # umount /mnt/rc_vdb1 root@rpmrepo01:/ # fdisk -l

Disk /dev/vda: 48.3 GB, 48318382080 bytes 16 heads, 63 sectors/track, 93622 cylinders Units = cylinders of 1008 * 512 = 516096 bytes Sector size (logical/physical): 512 bytes / 512 bytes I/O size (minimum/optimal): 512 bytes / 512 bytes Disk identifier: 0x0001795e

Device Boot Start End Blocks Id System /dev/vda1 * 3 409 204800 83 Linux Partition 1 does not end on cylinder boundary. /dev/vda2 409 93623 46980096 83 Linux Partition 2 does not end on cylinder boundary.

Disk /dev/sda: 5368 MB, 5368709120 bytes 9 heads, 40 sectors/track, 29127 cylinders Units = cylinders of 360 * 512 = 184320 bytes Sector size (logical/physical): 512 bytes / 512 bytes I/O size (minimum/optimal): 512 bytes / 512 bytes Disk identifier: 0xd4cd3997

Device Boot Start End Blocks Id System /dev/sda1 6 29128 5241856 83 Linux

Disk /dev/mapper/vg_rpmrepo01-lv_root: 6291 MB, 6291456000 bytes 255 heads, 63 sectors/track, 764 cylinders Units = cylinders of 16065 * 512 = 8225280 bytes Sector size (logical/physical): 512 bytes / 512 bytes I/O size (minimum/optimal): 512 bytes / 512 bytes Disk identifier: 0x00000000

Disk /dev/mapper/vg_rpmrepo01-lv_var: 41.8 GB, 41813016576 bytes 255 heads, 63 sectors/track, 5083 cylinders Units = cylinders of 16065 * 512 = 8225280 bytes Sector size (logical/physical): 512 bytes / 512 bytes I/O size (minimum/optimal): 512 bytes / 512 bytes Disk identifier: 0x00000000

Disk /dev/vdb: 4294 MB, 4294967296 bytes 16 heads, 63 sectors/track, 8322 cylinders Units = cylinders of 1008 * 512 = 516096 bytes Sector size (logical/physical): 512 bytes / 512 bytes I/O size (minimum/optimal): 512 bytes / 512 bytes Disk identifier: 0x00000000

root@rpmrepo01:/ # fdisk -uc /dev/vdb Device contains neither a valid DOS partition table, nor Sun, SGI or OSF disklabel Building a new DOS disklabel with disk identifier 0x1be62784. Changes will remain in memory only, until you decide to write them. After that, of course, the previous content won't be recoverable.

Warning: invalid flag 0x0000 of partition table 4 will be corrected by w(rite)

Command (m for help): n Command action e extended p primary partition (1-4) p Partition number (1-4): 1 First sector (2048-8388607, default 2048): Using default value 2048 Last sector, +sectors or +size{K,M,G} (2048-8388607, default 8388607): Using default value 8388607

Command (m for help): w The partition table has been altered!

Calling ioctl() to re-read partition table. Syncing disks. root@rpmrepo01:/ # pvcreate /dev/vdb1 Physical volume "/dev/vdb1" successfully created root@rpmrepo01:/ # vgcreate rapiddisk /dev/vdb1 Volume group "rapiddisk" successfully created root@rpmrepo01:/ # lvcreate -L3000 -n rapidlv rapiddisk Logical volume "rapidlv" created. root@rpmrepo01:/ # lsblk NAME MAJ:MIN RM SIZE RO TYPE MOUNTPOINT sr0 11:0 1 1024M 0 rom vda 252:0 0 45G 0 disk ??vda1 252:1 0 200M 0 part /boot ??vda2 252:2 0 44.8G 0 part ??vg_rpmrepo01-lv_root (dm-0) 253:0 0 5.9G 0 lvm / ??vg_rpmrepo01-lv_var (dm-1) 253:1 0 39G 0 lvm /var vdb 252:16 0 4G 0 disk ??vdb1 252:17 0 4G 0 part ??rapiddisk-rapidlv (dm-2) 253:2 0 3G 0 lvm sda 8:0 0 5G 0 disk ??sda1 8:1 0 5G 0 part rd0 251:0 0 64M 0 disk root@rpmrepo01:/ # mkfs.ext4 /dev/mapper/rapiddisk-rapidlv mke2fs 1.41.12 (17-May-2010) Filesystem label= OS type: Linux Block size=4096 (log=2) Fragment size=4096 (log=2) Stride=0 blocks, Stripe width=0 blocks 192000 inodes, 768000 blocks 38400 blocks (5.00%) reserved for the super user First data block=0 Maximum filesystem blocks=788529152 24 block groups 32768 blocks per group, 32768 fragments per group 8000 inodes per group Superblock backups stored on blocks: 32768, 98304, 163840, 229376, 294912

Writing inode tables: done Creating journal (16384 blocks): done Writing superblocks and filesystem accounting information: done

This filesystem will be automatically checked every 38 mounts or 180 days, whichever comes first. Use tune2fs -c or -i to override. root@rpmrepo01:/ # rapiddisk --list rapiddisk 4.4 Copyright 2011-2016 Petros Koutoupis

List of RapidDisk device(s):

RapidDisk Device 1: rd0 Size (KB): 65536

List of RapidDisk-Cache mapping(s):

None

root@rpmrepo01:/ # rapiddisk --cache-map rd0 /dev/mapper/rapiddisk-rapidlv rapiddisk 4.4 Copyright 2011-2016 Petros Koutoupis

Command to map rc-wt_rapiddisk-rapidlv with rd0 and /dev/mapper/rapiddisk-rapidlv has been sent. Verify with "--list"

root@rpmrepo01:/ # rapiddisk --list rapiddisk 4.4 Copyright 2011-2016 Petros Koutoupis

List of RapidDisk device(s):

RapidDisk Device 1: rd0 Size (KB): 65536

List of RapidDisk-Cache mapping(s):

RapidDisk-Cache Target 1: rc-wt_rapiddisk-rapidlv Cache: rd0 Target: dm-2 (WRITE THROUGH)

root@rpmrepo01:/ # mount /dev/mapper/rc-wt_rapiddisk-rapidlv /mnt/rc_vdb1/ root@rpmrepo01:/ # df -h Filesystem Size Used Avail Use% Mounted on /dev/mapper/vg_rpmrepo01-lv_root 5.7G 5.3G 132M 98% / tmpfs 938M 0 938M 0% /dev/shm /dev/vda1 194M 155M 30M 85% /boot /dev/mapper/vg_rpmrepo01-lv_var 39G 31G 6.2G 83% /var /dev/mapper/rc-wt_rapiddisk-rapidlv 2.9G 4.5M 2.7G 1% /mnt/rc_vdb1 root@rpmrepo01:/ # fio /usr/share/doc/fio-2.0.13/examples/ssd-test seq-read: (g=0): rw=read, bs=64K-64K/64K-64K/64K-64K, ioengine=libaio, iodepth=4 rand-read: (g=1): rw=randread, bs=64K-64K/64K-64K/64K-64K, ioengine=libaio, iodepth=4 seq-write: (g=2): rw=write, bs=64K-64K/64K-64K/64K-64K, ioengine=libaio, iodepth=4 rand-write: (g=3): rw=randwrite, bs=64K-64K/64K-64K/64K-64K, ioengine=libaio, iodepth=4 fio-2.0.13 Starting 4 processes seq-read: Laying out IO file(s) (1 file(s) / 1500MB)

????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????????

Session stopped

Network error: Software caused connection abort

ttpaus commented 8 years ago

I have the same problem using Ubuntu 16.04 and the 4.8 and 4.8.4 kernel that lockup the computer when running the rapiddisk --cache-map command. The 4.7.5 kernel works fine using the same Ubuntu 16.04 computer.

ttpaus

pkoutoupis commented 8 years ago

Thank you. Everyone's feedback is greatly appreciated. Although in my request, I was hoping to see the results of an Linear LVM volume without rapiddisk-cache. Just mount the LVM volume and run fio to it.

JvdW commented 8 years ago

On 15-11-2016 15:47, Petros Koutoupis wrote:

Thank you. Everyone's feedback is greatly appreciated. Although in my request, I was hoping to see the results of an Linear LVM volume without rapiddisk-cache. Just mount the LVM volume and run fio to it.

Sorry, but that just works. / is mounted on a lvm volume since its a standard Centos-6 installation.

Joop

pkoutoupis commented 8 years ago

ttpaus,

Please pull from the latest source code. I believe I addressed the 4.8 kernel bug.

pkoutoupis commented 8 years ago

Joop,

I am looking into this now.... I should have something soon.

ttpaus commented 8 years ago

It still crashes the 4.8.5 kernel. Tested using Ubuntu 16.04 with kernel 4.8.5 (Tested on the host. No VM) Work perfectly on kernel 4.7.5 ttpaus

pkoutoupis commented 8 years ago

ttpaus,

Can you provide the output of the the following commands:

$ sudo modinfo rapiddisk $ sudo modinfo rapiddisk-cache

ttpaus commented 8 years ago

I found the error. The 4.8.5 kernel refused to upgrade the rapiddisk module so it was still 4.4. Tried reinstall multiple times. I dont have time to find out why so I upgraded to the the latest kernel 4.8.8, rebuild and reinstalled rapiddisk and it worked perfectly. Thanks for the help ttpaus

pkoutoupis commented 8 years ago

Joop,

I have got some good news: I reproduced it and am not root causing it.

JvdW commented 8 years ago

On 24-11-2016 0:29, Petros Koutoupis wrote:

Joop,

I have got some good news: I reproduced it and am not root causing it.

I thought it wouldn't be rapiddisk since /dev/sda works and /dev/vda didn't but now you need to convince upstream that there is a bug :-)

I'll await what happens next and in the meantime will use /dev/sda when possible.

Thanks for updateing,

Joop

pkoutoupis commented 8 years ago

Oops. I meant to say "now root causing it" and not "not root causing it."

Anyway, it seems that virtio has issues with the type of spinlocks I am using. I need to figure out why.

pkoutoupis commented 8 years ago

Joop,

I pushed a branch that is using a different type of spinlock. In my tests, it works. Can you please validate that this is the case for you as well. If you are not familiar with the process, clone the master repo (with git) and switch to the branch: defect/virtio_spinlocks.

$ git checkout defect/virtio_spinlocks

You can validate the branch that you are on with the following command:

$ git branch
* defect/virtio_spinlocks
  master

Then run a "make" and insmod the rapiddisk-cache.ko module in the module/ directory. No lock up in my KVM guest (with virtio block device) and fio is running the exact same example script you were using (ssd-test.fio).

I am not saying that this is the fix but it will give me a better idea of why the other method is so problematic.

JvdW commented 8 years ago

On 24-11-2016 15:13, Petros Koutoupis wrote:

Joop,

I pushed a branch that is using a different type of spinlock. In my tests, it works. Can you please validate that this is the case for you as well. If you are not familiar with the process, clone the master repo (with git) and switch to the branch: defect/virtio_spinlocks.

|$ git checkout defect/virtio_spinlocks|

Then run a "make" and insmod the rapiddisk-cache.ko module in the module/ directory. No lock up in my KVM guest (with virtio block device) and fio is running the exact same example script you were using (ssd-test.fio).

I am not saying that this is the fix but it will give me a better idea of why the other method is so problematic.

Will try to do that today or tomorrow if no disasters show up.

Joop

JvdW commented 8 years ago

Just ran the fio test to completion using virtio :-)

pkoutoupis commented 8 years ago

Joop,

That is great news. Thank you very much for the feedback. I should have something official pushed shortly. When I do, I will close this ticket.

pkoutoupis commented 8 years ago

Officially merged into Release 5.0

commit d31d2e6822ae9d73dee15167b64af1584e2c08f0 Author: Petros Koutoupis petros@petroskoutoupis.com Date: Thu Nov 24 07:58:40 2016 -0600

Issue 13: Crashes while using it in a VM