From: Xiaoguang Wang Subject: Re: [PATCH] ext4: move_extent explicitly invalidate page buffers Date: Wed, 10 Dec 2014 19:04:54 +0800 Message-ID: <548828D6.60402@cn.fujitsu.com> References: <1414064938-2496-1-git-send-email-dmonakhov@openvz.org> <87a94n0wgs.fsf@openvz.org> <548810AE.6050901@cn.fujitsu.com> <87ppbrby37.fsf@openvz.org> Mime-Version: 1.0 Content-Type: text/plain; charset="ISO-8859-1" Content-Transfer-Encoding: 7bit Cc: , To: Dmitry Monakhov Return-path: Received: from cn.fujitsu.com ([59.151.112.132]:36762 "EHLO heian.cn.fujitsu.com" rhost-flags-OK-FAIL-OK-FAIL) by vger.kernel.org with ESMTP id S1750911AbaLJLIN (ORCPT ); Wed, 10 Dec 2014 06:08:13 -0500 In-Reply-To: <87ppbrby37.fsf@openvz.org> Sender: linux-ext4-owner@vger.kernel.org List-ID: Hi, On 12/10/2014 06:25 PM, Dmitry Monakhov wrote: > Xiaoguang Wang writes: > >> Hi, >> >> On 10/23/2014 09:03 PM, Dmitry Monakhov wrote: >>> Dmitry Monakhov writes: >>> >>>> In hard core test-cases such as ext4/301, ext4/302 some bh may >>>> becomes dirty so try_to_release_page() will fail and result in >>>> false positive EBUSY failures. We can easily fix that by >>>> explicit ->invalidatepage() after we holds page which is locked >>>> and uptodate. >>> Sorry. This patch is not correct. Please ignore it. >> >> Sorry to bother. >> I'd like to know whether you're going to send patches to fix this issue, thanks? > My initial patch was wrong. It is incorrect to use ->invalidatepage() > inside ext4_move_extents >> >> If try_to_release_page(...) failed, some bh's state would be BH_Uptodate, BH_Dirty, >> BH_Mapped and BH_Unwritten. In this patch, against such page, you call mext_page_mkuptodate(), >> but it then calls bh_submit_read()... I wonder whether we should start some write >> operation, thanks! > If we found BH_Dirty page the only thing we can to is to drop all locks > and start writeback on that page and then repeat attempt. Normally pages are > not dirty, because sane users call fsync before move_extents. > At the same time xfstests ext4/301,ext4/302, ext4/303 are stress tests so EBUSY is > expected to happen. IMHO it is reasonable to add explicit > filemap_write_and_wait_range() at the beginning of ext4_move_extents() > But this can not helps avoid EBUSY on stress tests complitely. I see, thanks for your detailed explanation. > So I'll change xfstests to ignore EBUSY similar to ENOSPC. > I'll be back with the patches. OK. Regards, Xiaoguang Wang >> I attached a log, which I once sent to you, thanks! >> >> Regards, >> Xiaoguang Wang >> >>>> >>>> Tested-by: Xiaoguang Wang >>>> Signed-off-by: Dmitry Monakhov >>>> --- >>>> fs/ext4/move_extent.c | 10 +++++++--- >>>> 1 files changed, 7 insertions(+), 3 deletions(-) >>>> >>>> diff --git a/fs/ext4/move_extent.c b/fs/ext4/move_extent.c >>>> index c2b2b02..76c45b6 100644 >>>> --- a/fs/ext4/move_extent.c >>>> +++ b/fs/ext4/move_extent.c >>>> @@ -348,8 +348,9 @@ again: >>>> !try_to_release_page(pagep[0], 0)) || >>>> (page_has_private(pagep[1]) && >>>> !try_to_release_page(pagep[1], 0))) { >>>> - *err = -EBUSY; >>>> - goto drop_data_sem; >>>> + /* One of buffers is busy, fall back data copy */ >>>> + ext4_double_up_write_data_sem(orig_inode, donor_inode); >>>> + goto data_copy; >>>> } >>>> replaced_count = ext4_swap_extents(handle, orig_inode, >>>> donor_inode, orig_blk_offset, >>>> @@ -360,12 +361,15 @@ again: >>>> goto unlock_pages; >>>> } >>>> data_copy: >>>> - *err = mext_page_mkuptodate(pagep[0], from, from + replaced_size); >>>> + /* In order to drop all buffers we have to make page fully uptodate */ >>>> + *err = mext_page_mkuptodate(pagep[0], 0, PAGE_CACHE_SIZE); >>>> if (*err) >>>> goto unlock_pages; >>>> >>>> /* At this point all buffers in range are uptodate, old mapping layout >>>> * is no longer required, try to drop it now. */ >>>> + do_invalidatepage(pagep[0], 0, PAGE_CACHE_SIZE); >>>> + do_invalidatepage(pagep[1], 0, PAGE_CACHE_SIZE); >>>> if ((page_has_private(pagep[0]) && !try_to_release_page(pagep[0], 0)) || >>>> (page_has_private(pagep[1]) && !try_to_release_page(pagep[1], 0))) { >>>> *err = -EBUSY; >>>> -- >>>> 1.7.1 >>>> >>>> -- >>>> To unsubscribe from this list: send the line "unsubscribe linux-ext4" in >>>> the body of a message to majordomo@vger.kernel.org >>>> More majordomo info at http://vger.kernel.org/majordomo-info.html >> >> >From a94dae03e2064548c2532ba7786a6cbc29b7bbc9 Mon Sep 17 00:00:00 2001 >> From: Dmitry Monakhov >> Date: Fri, 17 Oct 2014 11:24:37 +0400 >> Subject: [PATCH] patch bh-debug.patch >> >> >> Signed-off-by: Dmitry Monakhov >> --- >> fs/ext4/move_extent.c | 33 ++++++++++++++++++++++++++++----- >> 1 files changed, 28 insertions(+), 5 deletions(-) >> >> diff --git a/fs/ext4/move_extent.c b/fs/ext4/move_extent.c >> index c2b2b02..84ceed1 100644 >> --- a/fs/ext4/move_extent.c >> +++ b/fs/ext4/move_extent.c >> @@ -239,6 +239,21 @@ out: >> return 0; >> } >> >> + >> +static void dump_buffers(struct page *page) >> +{ >> + struct buffer_head *head = page_buffers(page); >> + struct buffer_head *bh; >> + >> + printk("%s ino:%ld page:%p idx:%lx fl:%lx\n", __FUNCTION__, >> + page->mapping->host->i_ino, page, page->index, page->flags); >> + bh = head; >> + do { >> + printk("bh:%p count:%d state:%lx\n", >> + bh, atomic_read(&bh->b_count), bh->b_state); >> + bh = bh->b_this_page; >> + } while (bh != head); >> +} >> /** >> * move_extent_per_page - Move extent data per page >> * >> @@ -344,10 +359,17 @@ again: >> ext4_double_up_write_data_sem(orig_inode, donor_inode); >> goto data_copy; >> } >> - if ((page_has_private(pagep[0]) && >> - !try_to_release_page(pagep[0], 0)) || >> - (page_has_private(pagep[1]) && >> - !try_to_release_page(pagep[1], 0))) { >> + if ((page_has_private(pagep[0]) && !try_to_release_page(pagep[0], 0))) { >> + if (retries > 95) >> + dump_buffers(pagep[0]); >> + >> + *err = -EBUSY; >> + goto drop_data_sem; >> + } >> + if ((page_has_private(pagep[1]) && !try_to_release_page(pagep[1], 0))) { >> + if (retries > 95) >> + dump_buffers(pagep[1]); >> + >> *err = -EBUSY; >> goto drop_data_sem; >> } >> @@ -408,10 +430,11 @@ stop_journal: >> /* Buffer was busy because probably is pinned to journal transaction, >> * force transaction commit may help to free it. */ >> if (*err == -EBUSY && >> - (retries++ < 4 && EXT4_SB(orig_inode->i_sb)->s_journal && >> + (retries++ < 100 && EXT4_SB(orig_inode->i_sb)->s_journal && >> jbd2_journal_force_commit_nested( >> EXT4_SB(orig_inode->i_sb)->s_journal))) >> goto again; >> + >> return replaced_count; >> >> repair_branches: >> -- >> 1.7.1 >> >> [ 0.000000] Initializing cgroup subsys cpuset >> [ 0.000000] Initializing cgroup subsys cpu >> [ 0.000000] Initializing cgroup subsys cpuacct >> [ 0.000000] Linux version 3.17.0-rc2+ (root@localhost.localdomain) (gcc version 4.8.2 20140120 (Red Hat 4.8.2-16) (GCC) ) #7 SMP Tue Oct 21 03:22:38 EDT 2014 >> [ 0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-3.17.0-rc2+ root=UUID=181da3e4-9fad-4bea-8f45-0d7fca5ae2b6 ro vconsole.keymap=us crashkernel=auto vconsole.font=latarcyrheb-sun16 rhgb quiet LANG=en_US.UTF-8 /dev/sda3 >> [ 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-0x000000007fffdfff] usable >> [ 0.000000] BIOS-e820: [mem 0x000000007fffe000-0x000000007fffffff] 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.4 present. >> [ 0.000000] DMI: Bochs Bochs, BIOS Bochs 01/01/2011 >> [ 0.000000] Hypervisor detected: KVM >> [ 0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved >> [ 0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable >> [ 0.000000] AGP: No AGP bridge found >> [ 0.000000] e820: last_pfn = 0x7fffe max_arch_pfn = 0x400000000 >> [ 0.000000] MTRR default type: write-back >> [ 0.000000] MTRR fixed ranges enabled: >> [ 0.000000] 00000-9FFFF write-back >> [ 0.000000] A0000-BFFFF uncachable >> [ 0.000000] C0000-FFFFF write-protect >> [ 0.000000] MTRR variable ranges enabled: >> [ 0.000000] 0 base 0080000000 mask FF80000000 uncachable >> [ 0.000000] 1 disabled >> [ 0.000000] 2 disabled >> [ 0.000000] 3 disabled >> [ 0.000000] 4 disabled >> [ 0.000000] 5 disabled >> [ 0.000000] 6 disabled >> [ 0.000000] 7 disabled >> [ 0.000000] PAT not supported by CPU. >> [ 0.000000] found SMP MP-table at [mem 0x000fda90-0x000fda9f] mapped at [ffff8800000fda90] >> [ 0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576 >> [ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff] >> [ 0.000000] [mem 0x00000000-0x000fffff] page 4k >> [ 0.000000] BRK [0x01f9c000, 0x01f9cfff] PGTABLE >> [ 0.000000] BRK [0x01f9d000, 0x01f9dfff] PGTABLE >> [ 0.000000] BRK [0x01f9e000, 0x01f9efff] PGTABLE >> [ 0.000000] init_memory_mapping: [mem 0x7fc00000-0x7fdfffff] >> [ 0.000000] [mem 0x7fc00000-0x7fdfffff] page 2M >> [ 0.000000] BRK [0x01f9f000, 0x01f9ffff] PGTABLE >> [ 0.000000] init_memory_mapping: [mem 0x7c000000-0x7fbfffff] >> [ 0.000000] [mem 0x7c000000-0x7fbfffff] page 2M >> [ 0.000000] init_memory_mapping: [mem 0x00100000-0x7bffffff] >> [ 0.000000] [mem 0x00100000-0x001fffff] page 4k >> [ 0.000000] [mem 0x00200000-0x7bffffff] page 2M >> [ 0.000000] init_memory_mapping: [mem 0x7fe00000-0x7fffdfff] >> [ 0.000000] [mem 0x7fe00000-0x7fffdfff] page 4k >> [ 0.000000] BRK [0x01fa0000, 0x01fa0fff] PGTABLE >> [ 0.000000] RAMDISK: [mem 0x34d30000-0x3668ffff] >> [ 0.000000] ACPI: Early table checksum verification disabled >> [ 0.000000] ACPI: RSDP 0x00000000000FD8B0 000014 (v00 BOCHS ) >> [ 0.000000] ACPI: RSDT 0x000000007FFFE380 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001) >> [ 0.000000] ACPI: FACP 0x000000007FFFFF80 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) >> [ 0.000000] ACPI: DSDT 0x000000007FFFE3C0 0011A9 (v01 BXPC BXDSDT 00000001 INTL 20100528) >> [ 0.000000] ACPI: FACS 0x000000007FFFFF40 000040 >> [ 0.000000] ACPI: SSDT 0x000000007FFFF6E0 000858 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001) >> [ 0.000000] ACPI: APIC 0x000000007FFFF5B0 000090 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) >> [ 0.000000] ACPI: HPET 0x000000007FFFF570 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) >> [ 0.000000] ACPI: Local APIC address 0xfee00000 >> [ 0.000000] No NUMA configuration found >> [ 0.000000] Faking a node at [mem 0x0000000000000000-0x000000007fffdfff] >> [ 0.000000] Initmem setup node 0 [mem 0x00000000-0x7fffdfff] >> [ 0.000000] NODE_DATA [mem 0x7ffd8000-0x7fffdfff] >> [ 0.000000] kexec: crashkernel: memory value expected >> [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 >> [ 0.000000] kvm-clock: cpu 0, msr 0:7ff58001, primary cpu clock >> [ 0.000000] [ffffea0000000000-ffffea0001ffffff] PMD -> [ffff88007d600000-ffff88007f5fffff] on node 0 >> [ 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-0x7fffdfff] >> [ 0.000000] On node 0 totalpages: 524188 >> [ 0.000000] DMA zone: 64 pages used for memmap >> [ 0.000000] DMA zone: 21 pages reserved >> [ 0.000000] DMA zone: 3998 pages, LIFO batch:0 >> [ 0.000000] DMA32 zone: 8128 pages used for memmap >> [ 0.000000] DMA32 zone: 520190 pages, LIFO batch:31 >> [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 >> [ 0.000000] ACPI: Local APIC address 0xfee00000 >> [ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled) >> [ 0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled) >> [ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x02] enabled) >> [ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x03] enabled) >> [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) >> [ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0]) >> [ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 >> [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) >> [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) >> [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) >> [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) >> [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) >> [ 0.000000] ACPI: IRQ0 used by override. >> [ 0.000000] ACPI: IRQ5 used by override. >> [ 0.000000] ACPI: IRQ9 used by override. >> [ 0.000000] ACPI: IRQ10 used by override. >> [ 0.000000] ACPI: IRQ11 used by override. >> [ 0.000000] Using ACPI (MADT) for SMP configuration information >> [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 >> [ 0.000000] smpboot: Allowing 4 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 0x80000000-0xfeffbfff] available for PCI devices >> [ 0.000000] Booting paravirtualized kernel on KVM >> [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 >> [ 0.000000] PERCPU: Embedded 29 pages/cpu @ffff88007fc00000 s87232 r8192 d23360 u524288 >> [ 0.000000] pcpu-alloc: s87232 r8192 d23360 u524288 alloc=1*2097152 >> [ 0.000000] pcpu-alloc: [0] 0 1 2 3 >> [ 0.000000] KVM setup async PF for cpu 0 >> [ 0.000000] kvm-stealtime: cpu 0, msr 7fc0e000 >> [ 0.000000] Built 1 zonelists in Node order, mobility grouping on. Total pages: 515975 >> [ 0.000000] Policy zone: DMA32 >> [ 0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-3.17.0-rc2+ root=UUID=181da3e4-9fad-4bea-8f45-0d7fca5ae2b6 ro vconsole.keymap=us crashkernel=auto vconsole.font=latarcyrheb-sun16 rhgb quiet LANG=en_US.UTF-8 /dev/sda3 >> [ 0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes) >> [ 0.000000] AGP: Checking aperture... >> [ 0.000000] AGP: No AGP bridge found >> [ 0.000000] Memory: 2020524K/2096752K available (6864K kernel code, 1463K rwdata, 3240K rodata, 1724K init, 2640K bss, 76228K reserved) >> [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 >> [ 0.000000] Hierarchical RCU implementation. >> [ 0.000000] RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. >> [ 0.000000] Offload RCU callbacks from all CPUs >> [ 0.000000] Offload RCU callbacks from CPUs: 0-3. >> [ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 >> [ 0.000000] NR_IRQS:524544 nr_irqs:456 0 >> [ 0.000000] Console: colour VGA+ 80x25 >> [ 0.000000] console [tty0] enabled >> [ 0.000000] allocated 8388608 bytes of page_cgroup >> [ 0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups >> [ 0.000000] hpet clockevent registered >> [ 0.000000] tsc: Detected 3192.746 MHz processor >> [ 0.002000] Calibrating delay loop (skipped) preset value.. 6385.49 BogoMIPS (lpj=3192746) >> [ 0.002000] pid_max: default: 32768 minimum: 301 >> [ 0.002000] ACPI: Core revision 20140724 >> [ 0.002000] ACPI: All ACPI Tables successfully acquired >> [ 0.002000] Security Framework initialized >> [ 0.002000] SELinux: Initializing. >> [ 0.002000] SELinux: Starting in permissive mode >> [ 0.002000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes) >> [ 0.002356] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes) >> [ 0.002529] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes) >> [ 0.002532] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes) >> [ 0.002649] Initializing cgroup subsys memory >> [ 0.002652] Initializing cgroup subsys devices >> [ 0.002654] Initializing cgroup subsys freezer >> [ 0.002656] Initializing cgroup subsys net_cls >> [ 0.002657] Initializing cgroup subsys blkio >> [ 0.002658] Initializing cgroup subsys perf_event >> [ 0.002660] Initializing cgroup subsys hugetlb >> [ 0.002706] mce: CPU supports 10 MCE banks >> [ 0.002739] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 >> Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 >> [ 0.002877] Freeing SMP alternatives memory: 28K (ffffffff81cff000 - ffffffff81d06000) >> [ 0.005420] ftrace: allocating 25457 entries in 100 pages >> [ 0.011272] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 >> [ 0.011275] smpboot: CPU0: Intel QEMU Virtual CPU version 1.4.2 (fam: 06, model: 02, stepping: 03) >> [ 0.012000] APIC calibration not consistent with PM-Timer: 116ms instead of 100ms >> [ 0.012000] APIC delta adjusted to PM-Timer: 6249964 (7250436) >> [ 0.012000] Performance Events: unsupported p6 CPU model 2 no PMU driver, software events only. >> [ 0.012015] NMI watchdog: disabled (cpu0): hardware events not enabled >> [ 0.012044] x86: Booting SMP configuration: >> [ 0.012045] .... node #0, CPUs: #1 >> [ 0.012000] kvm-clock: cpu 1, msr 0:7ff58041, secondary cpu clock >> [ 0.025011] KVM setup async PF for cpu 1 >> [ 0.025016] kvm-stealtime: cpu 1, msr 7fc8e000 >> [ 0.025091] #2 >> [ 0.025014] kvm-clock: cpu 2, msr 0:7ff58081, secondary cpu clock >> [ 0.038011] KVM setup async PF for cpu 2 >> [ 0.038016] kvm-stealtime: cpu 2, msr 7fd0e000 >> [ 0.038085] #3 >> [ 0.038016] kvm-clock: cpu 3, msr 0:7ff580c1, secondary cpu clock >> [ 0.051032] x86: Booted up 1 node, 4 CPUs >> [ 0.051036] smpboot: Total of 4 processors activated (25541.96 BogoMIPS) >> [ 0.051016] KVM setup async PF for cpu 3 >> [ 0.051020] kvm-stealtime: cpu 3, msr 7fd8e000 >> [ 0.055004] devtmpfs: initialized >> [ 0.056661] evm: security.selinux >> [ 0.056663] evm: security.ima >> [ 0.056663] evm: security.capability >> [ 0.057559] atomic64_test: passed for x86-64 platform with CX8 and with SSE >> [ 0.057637] NET: Registered protocol family 16 >> [ 0.057746] cpuidle: using governor menu >> [ 0.058042] ACPI: bus type PCI registered >> [ 0.058044] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 >> [ 0.058143] PCI: Using configuration type 1 for base access >> [ 0.061021] ACPI: Added _OSI(Module Device) >> [ 0.061023] ACPI: Added _OSI(Processor Device) >> [ 0.061024] ACPI: Added _OSI(3.0 _SCP Extensions) >> [ 0.061025] ACPI: Added _OSI(Processor Aggregator Device) >> [ 0.062323] ACPI: Interpreter enabled >> [ 0.062326] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20140724/hwxface-580) >> [ 0.062329] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20140724/hwxface-580) >> [ 0.062336] ACPI: (supports S0 S3 S4 S5) >> [ 0.062338] ACPI: Using IOAPIC for interrupt routing >> [ 0.062348] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug >> [ 0.064186] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) >> [ 0.064191] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI] >> [ 0.064195] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM >> [ 0.064272] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. >> [ 0.064505] acpiphp: Slot [3] registered >> [ 0.064521] acpiphp: Slot [4] registered >> [ 0.064536] acpiphp: Slot [5] registered >> [ 0.064551] acpiphp: Slot [6] registered >> [ 0.064567] acpiphp: Slot [7] registered >> [ 0.064583] acpiphp: Slot [8] registered >> [ 0.064599] acpiphp: Slot [9] registered >> [ 0.064614] acpiphp: Slot [10] registered >> [ 0.064628] acpiphp: Slot [11] registered >> [ 0.064643] acpiphp: Slot [12] registered >> [ 0.064659] acpiphp: Slot [13] registered >> [ 0.064675] acpiphp: Slot [14] registered >> [ 0.064691] acpiphp: Slot [15] registered >> [ 0.064705] acpiphp: Slot [16] registered >> [ 0.064720] acpiphp: Slot [17] registered >> [ 0.064735] acpiphp: Slot [18] registered >> [ 0.064751] acpiphp: Slot [19] registered >> [ 0.064766] acpiphp: Slot [20] registered >> [ 0.064782] acpiphp: Slot [21] registered >> [ 0.064798] acpiphp: Slot [22] registered >> [ 0.064812] acpiphp: Slot [23] registered >> [ 0.064827] acpiphp: Slot [24] registered >> [ 0.064842] acpiphp: Slot [25] registered >> [ 0.064857] acpiphp: Slot [26] registered >> [ 0.064871] acpiphp: Slot [27] registered >> [ 0.064887] acpiphp: Slot [28] registered >> [ 0.064902] acpiphp: Slot [29] registered >> [ 0.064917] acpiphp: Slot [30] registered >> [ 0.064932] acpiphp: Slot [31] registered >> [ 0.064940] PCI host bridge to bus 0000:00 >> [ 0.064942] pci_bus 0000:00: root bus resource [bus 00-ff] >> [ 0.064943] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7] >> [ 0.064945] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff] >> [ 0.064946] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff] >> [ 0.064948] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff] >> [ 0.064977] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 >> [ 0.065262] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 >> [ 0.065654] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 >> [ 0.069415] pci 0000:00:01.1: reg 0x20: [io 0xc0e0-0xc0ef] >> [ 0.071027] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] >> [ 0.071029] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] >> [ 0.071030] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] >> [ 0.071031] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] >> [ 0.071177] pci 0000:00:01.2: [8086:7020] type 00 class 0x0c0300 >> [ 0.075002] pci 0000:00:01.2: reg 0x20: [io 0xc040-0xc05f] >> [ 0.077042] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 >> [ 0.077311] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI >> [ 0.077318] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB >> [ 0.077485] pci 0000:00:02.0: [1b36:0100] type 00 class 0x030000 >> [ 0.079007] pci 0000:00:02.0: reg 0x10: [mem 0xf4000000-0xf7ffffff] >> [ 0.081006] pci 0000:00:02.0: reg 0x14: [mem 0xf8000000-0xfbffffff] >> [ 0.083005] pci 0000:00:02.0: reg 0x18: [mem 0xfc024000-0xfc025fff] >> [ 0.085002] pci 0000:00:02.0: reg 0x1c: [io 0xc060-0xc07f] >> [ 0.091005] pci 0000:00:02.0: reg 0x30: [mem 0xfc000000-0xfc00ffff pref] >> [ 0.091176] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 >> [ 0.092663] pci 0000:00:03.0: reg 0x10: [io 0xc080-0xc09f] >> [ 0.094003] pci 0000:00:03.0: reg 0x14: [mem 0xfc026000-0xfc026fff] >> [ 0.100648] pci 0000:00:03.0: reg 0x30: [mem 0xfc010000-0xfc01ffff pref] >> [ 0.101025] pci 0000:00:04.0: [8086:2668] type 00 class 0x040300 >> [ 0.101733] pci 0000:00:04.0: reg 0x10: [mem 0xfc020000-0xfc023fff] >> [ 0.106070] pci 0000:00:05.0: [1af4:1003] type 00 class 0x078000 >> [ 0.107003] pci 0000:00:05.0: reg 0x10: [io 0xc0a0-0xc0bf] >> [ 0.108743] pci 0000:00:05.0: reg 0x14: [mem 0xfc027000-0xfc027fff] >> [ 0.115257] pci 0000:00:06.0: [1af4:1001] type 00 class 0x010000 >> [ 0.116644] pci 0000:00:06.0: reg 0x10: [io 0xc000-0xc03f] >> [ 0.118015] pci 0000:00:06.0: reg 0x14: [mem 0xfc028000-0xfc028fff] >> [ 0.125150] pci 0000:00:07.0: [1af4:1002] type 00 class 0x00ff00 >> [ 0.125841] pci 0000:00:07.0: reg 0x10: [io 0xc0c0-0xc0df] >> [ 0.130272] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11) >> [ 0.130337] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11) >> [ 0.130399] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11) >> [ 0.130455] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11) >> [ 0.130485] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) >> [ 0.130856] ACPI: Enabled 16 GPEs in block 00 to 0F >> [ 0.131051] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none >> [ 0.131053] vgaarb: loaded >> [ 0.131054] vgaarb: bridge control possible 0000:00:02.0 >> [ 0.131113] SCSI subsystem initialized >> [ 0.131132] ACPI: bus type USB registered >> [ 0.131145] usbcore: registered new interface driver usbfs >> [ 0.131151] usbcore: registered new interface driver hub >> [ 0.131178] usbcore: registered new device driver usb >> [ 0.131178] PCI: Using ACPI for IRQ routing >> [ 0.131178] PCI: pci_cache_line_size set to 64 bytes >> [ 0.131236] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] >> [ 0.131238] e820: reserve RAM buffer [mem 0x7fffe000-0x7fffffff] >> [ 0.131316] NetLabel: Initializing >> [ 0.131317] NetLabel: domain hash size = 128 >> [ 0.131317] NetLabel: protocols = UNLABELED CIPSOv4 >> [ 0.131328] NetLabel: unlabeled traffic allowed by default >> [ 0.131371] HPET: 3 timers in total, 0 timers will be used for per-cpu timer >> [ 0.131382] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 >> [ 0.131385] hpet0: 3 comparators, 64-bit 100.000000 MHz counter >> [ 0.135064] Switched to clocksource kvm-clock >> [ 0.140209] pnp: PnP ACPI init >> [ 0.140272] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active) >> [ 0.140303] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active) >> [ 0.140321] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active) >> [ 0.140342] pnp 00:03: [dma 2] >> [ 0.140352] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active) >> [ 0.140422] pnp 00:04: Plug and Play ACPI device, IDs PNP0501 (active) >> [ 0.140587] pnp: PnP ACPI: found 5 devices >> [ 0.151666] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7] >> [ 0.151669] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff] >> [ 0.151670] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff] >> [ 0.151671] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff] >> [ 0.151709] NET: Registered protocol family 2 >> [ 0.151823] TCP established hash table entries: 16384 (order: 5, 131072 bytes) >> [ 0.151851] TCP bind hash table entries: 16384 (order: 6, 262144 bytes) >> [ 0.151882] TCP: Hash tables configured (established 16384 bind 16384) >> [ 0.151895] TCP: reno registered >> [ 0.151898] UDP hash table entries: 1024 (order: 3, 32768 bytes) >> [ 0.151905] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes) >> [ 0.151938] NET: Registered protocol family 1 >> [ 0.151948] pci 0000:00:00.0: Limiting direct PCI/PCI transfers >> [ 0.151965] pci 0000:00:01.0: PIIX3: Enabling Passive Release >> [ 0.151979] pci 0000:00:01.0: Activating ISA DMA hang workarounds >> [ 0.152214] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 11 >> [ 0.152666] pci 0000:00:02.0: Boot video device >> [ 0.152704] PCI: CLS 0 bytes, default 64 >> [ 0.152769] Unpacking initramfs... >> [ 0.443991] Freeing initrd memory: 25984K (ffff880034d30000 - ffff880036690000) >> [ 0.444414] microcode: CPU0 sig=0x623, pf=0x0, revision=0x1 >> [ 0.444452] microcode: CPU1 sig=0x623, pf=0x0, revision=0x1 >> [ 0.444490] microcode: CPU2 sig=0x623, pf=0x0, revision=0x1 >> [ 0.444527] microcode: CPU3 sig=0x623, pf=0x0, revision=0x1 >> [ 0.444614] microcode: Microcode Update Driver: v2.00 , Peter Oruba >> [ 0.445044] futex hash table entries: 1024 (order: 4, 65536 bytes) >> [ 0.445067] Initialise system trusted keyring >> [ 0.445096] audit: initializing netlink subsys (disabled) >> [ 0.445120] audit: type=2000 audit(1413877341.862:1): initialized >> [ 0.445707] HugeTLB registered 2 MB page size, pre-allocated 0 pages >> [ 0.447094] zpool: loaded >> [ 0.447096] zbud: loaded >> [ 0.447316] VFS: Disk quotas dquot_6.5.2 >> [ 0.447345] Dquot-cache hash table entries: 512 (order 0, 4096 bytes) >> [ 0.447570] msgmni has been set to 3997 >> [ 0.447607] Key type big_key registered >> [ 0.447610] SELinux: Registering netfilter hooks >> [ 0.449183] alg: No test for stdrng (krng) >> [ 0.449189] NET: Registered protocol family 38 >> [ 0.449195] Key type asymmetric registered >> [ 0.449197] Asymmetric key parser 'x509' registered >> [ 0.449235] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252) >> [ 0.449296] io scheduler noop registered >> [ 0.449299] io scheduler deadline registered (default) >> [ 0.449322] io scheduler cfq registered >> [ 0.449388] pci_hotplug: PCI Hot Plug PCI Core version: 0.5 >> [ 0.449399] pciehp: PCI Express Hot Plug Controller Driver version: 0.4 >> [ 0.449426] intel_idle: does not run on family 6 model 2 >> [ 0.449473] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 >> [ 0.449476] ACPI: Power Button [PWRF] >> [ 0.449716] GHES: HEST is not enabled! >> [ 0.449766] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled >> [ 0.471023] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A >> [ 0.471309] Non-volatile memory driver v1.3 >> [ 0.471311] Linux agpgart interface v0.103 >> [ 0.471393] rdac: device handler registered >> [ 0.471472] hp_sw: device handler registered >> [ 0.471474] emc: device handler registered >> [ 0.471476] alua: device handler registered >> [ 0.471499] libphy: Fixed MDIO Bus: probed >> [ 0.471523] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver >> [ 0.471526] ehci-pci: EHCI PCI platform driver >> [ 0.471533] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver >> [ 0.471535] ohci-pci: OHCI PCI platform driver >> [ 0.471541] uhci_hcd: USB Universal Host Controller Interface driver >> [ 0.472109] uhci_hcd 0000:00:01.2: UHCI Host Controller >> [ 0.472135] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 >> [ 0.472149] uhci_hcd 0000:00:01.2: detected 2 ports >> [ 0.472218] uhci_hcd 0000:00:01.2: irq 11, io base 0x0000c040 >> [ 0.472271] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001 >> [ 0.472273] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 >> [ 0.472274] usb usb1: Product: UHCI Host Controller >> [ 0.472275] usb usb1: Manufacturer: Linux 3.17.0-rc2+ uhci_hcd >> [ 0.472276] usb usb1: SerialNumber: 0000:00:01.2 >> [ 0.472337] hub 1-0:1.0: USB hub found >> [ 0.472341] hub 1-0:1.0: 2 ports detected >> [ 0.472435] usbcore: registered new interface driver usbserial >> [ 0.472439] usbcore: registered new interface driver usbserial_generic >> [ 0.472444] usbserial: USB Serial support registered for generic >> [ 0.472462] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 >> [ 0.473029] serio: i8042 KBD port at 0x60,0x64 irq 1 >> [ 0.473035] serio: i8042 AUX port at 0x60,0x64 irq 12 >> [ 0.473103] mousedev: PS/2 mouse device common for all mice >> [ 0.473339] rtc_cmos 00:00: RTC can wake from S4 >> [ 0.473599] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0 >> [ 0.473762] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs >> [ 0.473910] hidraw: raw HID events driver (C) Jiri Kosina >> [ 0.474128] usbcore: registered new interface driver usbhid >> [ 0.474130] usbhid: USB HID core driver >> [ 0.474165] drop_monitor: Initializing network drop monitor service >> [ 0.474268] TCP: cubic registered >> [ 0.474277] Initializing XFRM netlink socket >> [ 0.474430] NET: Registered protocol family 10 >> [ 0.475079] NET: Registered protocol family 17 >> [ 0.475799] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 >> [ 0.475890] Loading compiled-in X.509 certificates >> [ 0.477235] Loaded X.509 cert 'Magrathea: Glacier signing key: 2fe9051af95b7482e9d55ea1ec379806e2f72232' >> [ 0.477262] registered taskstats version 1 >> [ 0.479676] Key type trusted registered >> [ 0.482046] Key type encrypted registered >> [ 0.484077] ima: No TPM chip found, activating TPM-bypass! >> [ 0.484109] evm: HMAC attrs: 0x1 >> [ 0.484740] rtc_cmos 00:00: setting system clock to 2014-10-21 07:42:21 UTC (1413877341) >> [ 0.485729] Freeing unused kernel memory: 1724K (ffffffff81b50000 - ffffffff81cff000) >> [ 0.489335] systemd[1]: systemd 208 running in system mode. (+PAM +LIBWRAP +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ) >> [ 0.489391] systemd[1]: Detected virtualization 'kvm'. >> [ 0.489396] systemd[1]: Running in initial RAM disk. >> [ 0.489459] systemd[1]: Set hostname to . >> [ 0.503906] random: systemd urandom read with 5 bits of entropy available >> [ 0.533840] systemd[1]: Expecting device dev-disk-by\x2duuid-181da3e4\x2d9fad\x2d4bea\x2d8f45\x2d0d7fca5ae2b6.device... >> [ 0.533861] systemd[1]: Starting -.slice. >> [ 0.534268] systemd[1]: Created slice -.slice. >> [ 0.534324] systemd[1]: Starting System Slice. >> [ 0.534419] systemd[1]: Created slice System Slice. >> [ 0.534466] systemd[1]: Starting Slices. >> [ 0.534481] systemd[1]: Reached target Slices. >> [ 0.534519] systemd[1]: Starting Timers. >> [ 0.534532] systemd[1]: Reached target Timers. >> [ 0.534574] systemd[1]: Starting udev Kernel Socket. >> [ 0.534605] systemd[1]: Listening on udev Kernel Socket. >> [ 0.534649] systemd[1]: Starting udev Control Socket. >> [ 0.534687] systemd[1]: Listening on udev Control Socket. >> [ 0.534730] systemd[1]: Starting Journal Socket. >> [ 0.534785] systemd[1]: Listening on Journal Socket. >> [ 0.535550] systemd[1]: Starting dracut cmdline hook... >> [ 0.536124] systemd[1]: Starting Create list of required static device nodes for the current kernel... >> [ 0.536700] systemd[1]: Started Load Kernel Modules. >> [ 0.536721] systemd[1]: Starting Sockets. >> [ 0.536735] systemd[1]: Reached target Sockets. >> [ 0.536786] systemd[1]: Starting Setup Virtual Console... >> [ 0.537189] systemd[1]: Starting Journal Service... >> [ 0.537601] systemd[1]: Started Journal Service. >> [ 0.543609] systemd-journald[108]: Vacuuming done, freed 0 bytes >> [ 0.654429] systemd-udevd[213]: starting version 208 >> [ 0.683839] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 10 >> [ 0.690338] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10 >> [ 0.690471] libata version 3.00 loaded. >> [ 0.691758] ACPI: PCI Interrupt Link [LNKB] enabled at IRQ 11 >> [ 0.707546] ata_piix 0000:00:01.1: version 2.13 >> [ 0.709232] virtio-pci 0000:00:06.0: irq 24 for MSI/MSI-X >> [ 0.709248] virtio-pci 0000:00:06.0: irq 25 for MSI/MSI-X >> [ 0.709255] virtio-pci 0000:00:03.0: irq 26 for MSI/MSI-X >> [ 0.709276] virtio-pci 0000:00:03.0: irq 27 for MSI/MSI-X >> [ 0.709290] virtio-pci 0000:00:03.0: irq 28 for MSI/MSI-X >> [ 0.709522] scsi host0: ata_piix >> [ 0.709640] scsi host1: ata_piix >> [ 0.709675] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc0e0 irq 14 >> [ 0.709676] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc0e8 irq 15 >> [ 0.743717] [drm] Initialized drm 1.1.0 20060810 >> [ 0.743895] FDC 0 is a S82078B >> [ 0.792255] [drm] Device Version 0.0 >> [ 0.792257] [drm] Compression level 0 log level 0 >> [ 0.792258] [drm] Currently using mode #0, list at 0x488 >> [ 0.792259] [drm] 12286 io pages at offset 0x1000000 >> [ 0.792260] [drm] 16777216 byte draw area at offset 0x0 >> [ 0.792260] [drm] RAM header offset: 0x3ffe000 >> [ 0.792261] [drm] rom modes offset 0x488 for 118 modes >> [ 0.792569] [TTM] Zone kernel: Available graphics memory: 1024130 kiB >> [ 0.792571] [TTM] Initializing pool allocator >> [ 0.792575] [TTM] Initializing DMA pool allocator >> [ 0.792581] [drm] qxl: 16M of VRAM memory size >> [ 0.792581] [drm] qxl: 63M of IO pages memory ready (VRAM domain) >> [ 0.792582] [drm] qxl: 64M of Surface memory size >> [ 0.794057] usb 1-1: new full-speed USB device number 2 using uhci_hcd >> [ 0.795164] [drm] main mem slot 1 [f4000000,3ffe000] >> [ 0.795166] [drm] surface mem slot 2 [f8000000,4000000] >> [ 0.796755] [drm] fb mappable at 0xF4000000, size 3145728 >> [ 0.796759] [drm] fb: depth 24, pitch 4096, width 1024, height 768 >> [ 0.796851] fbcon: qxldrmfb (fb0) is primary device >> [ 0.800086] vda: vda1 >> [ 0.807924] Console: switching to colour frame buffer device 128x48 >> [ 0.809915] qxl 0000:00:02.0: fb0: qxldrmfb frame buffer device >> [ 0.809916] qxl 0000:00:02.0: registered panic notifier >> [ 0.817301] [drm] Initialized qxl 0.1.0 20120117 for 0000:00:02.0 on minor 0 >> [ 0.893432] ata2.01: NODEV after polling detection >> [ 0.893695] ata2.00: ATAPI: QEMU DVD-ROM, 1.4.2, max UDMA/100 >> [ 0.894291] ata2.00: configured for MWDMA2 >> [ 0.895457] ata1.01: NODEV after polling detection >> [ 0.895828] ata1.00: ATA-7: QEMU HARDDISK, 1.4.2, max UDMA/100 >> [ 0.895832] ata1.00: 83886080 sectors, multi 16: LBA48 >> [ 0.896380] ata1.00: configured for MWDMA2 >> [ 0.896478] scsi 0:0:0:0: Direct-Access ATA QEMU HARDDISK 2 PQ: 0 ANSI: 5 >> [ 0.896983] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 1.4. PQ: 0 ANSI: 5 >> [ 0.916776] sd 0:0:0:0: [sda] 83886080 512-byte logical blocks: (42.9 GB/40.0 GiB) >> [ 0.916801] sd 0:0:0:0: [sda] Write Protect is off >> [ 0.916803] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00 >> [ 0.916813] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA >> [ 0.917922] sda: sda1 sda2 sda3 >> [ 0.918117] sd 0:0:0:0: [sda] Attached SCSI disk >> [ 0.927868] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray >> [ 0.927871] cdrom: Uniform CD-ROM driver Revision: 3.20 >> [ 0.928004] sr 1:0:0:0: Attached scsi CD-ROM sr0 >> [ 0.942668] usb 1-1: New USB device found, idVendor=0627, idProduct=0001 >> [ 0.942673] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=5 >> [ 0.942676] usb 1-1: Product: QEMU USB Tablet >> [ 0.942679] usb 1-1: Manufacturer: QEMU >> [ 0.942681] usb 1-1: SerialNumber: 42 >> [ 0.951947] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.2/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input4 >> [ 0.952225] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Pointer [QEMU QEMU USB Tablet] on usb-0000:00:01.2-1/input0 >> [ 1.324142] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 >> [ 1.447051] tsc: Refined TSC clocksource calibration: 3192.751 MHz >> [ 2.344620] random: nonblocking pool is initialized >> [ 6.156783] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null) >> [ 6.455997] systemd-journald[108]: Received SIGTERM >> [ 7.125358] audit: type=1404 audit(1413877348.140:2): enforcing=1 old_enforcing=0 auid=4294967295 ses=4294967295 >> [ 7.227565] SELinux: 2048 avtab hash slots, 106409 rules. >> [ 7.239107] SELinux: 2048 avtab hash slots, 106409 rules. >> [ 7.259541] SELinux: 8 users, 86 roles, 4801 types, 280 bools, 1 sens, 1024 cats >> [ 7.259544] SELinux: 83 classes, 106409 rules >> [ 7.263004] SELinux: Permission audit_read in class capability2 not defined in policy. >> [ 7.263014] SELinux: the above unknown classes and permissions will be allowed >> [ 7.263017] SELinux: Completing initialization. >> [ 7.263018] SELinux: Setting up existing superblocks. >> [ 7.263022] SELinux: initialized (dev rootfs, type rootfs), uses genfs_contexts >> [ 7.263030] SELinux: initialized (dev bdev, type bdev), uses genfs_contexts >> [ 7.263033] SELinux: initialized (dev proc, type proc), uses genfs_contexts >> [ 7.263055] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs >> [ 7.263106] SELinux: initialized (dev devtmpfs, type devtmpfs), uses transition SIDs >> [ 7.263552] SELinux: initialized (dev sockfs, type sockfs), uses task SIDs >> [ 7.263555] SELinux: initialized (dev debugfs, type debugfs), uses genfs_contexts >> [ 7.264107] SELinux: initialized (dev pipefs, type pipefs), uses task SIDs >> [ 7.264110] SELinux: initialized (dev anon_inodefs, type anon_inodefs), uses genfs_contexts >> [ 7.264111] SELinux: initialized (dev aio, type aio), not configured for labeling >> [ 7.264113] SELinux: initialized (dev devpts, type devpts), uses transition SIDs >> [ 7.264124] SELinux: initialized (dev hugetlbfs, type hugetlbfs), uses transition SIDs >> [ 7.264128] SELinux: initialized (dev mqueue, type mqueue), uses transition SIDs >> [ 7.264132] SELinux: initialized (dev selinuxfs, type selinuxfs), uses genfs_contexts >> [ 7.264139] SELinux: initialized (dev securityfs, type securityfs), uses genfs_contexts >> [ 7.264142] SELinux: initialized (dev sysfs, type sysfs), uses genfs_contexts >> [ 7.264286] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs >> [ 7.264290] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs >> [ 7.264348] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs >> [ 7.264368] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts >> [ 7.264371] SELinux: initialized (dev pstore, type pstore), uses genfs_contexts >> [ 7.264373] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts >> [ 7.264374] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts >> [ 7.264376] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts >> [ 7.264380] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts >> [ 7.264382] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts >> [ 7.264383] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts >> [ 7.264385] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts >> [ 7.264389] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts >> [ 7.264391] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts >> [ 7.264393] SELinux: initialized (dev configfs, type configfs), uses genfs_contexts >> [ 7.264394] SELinux: initialized (dev drm, type drm), not configured for labeling >> [ 7.264400] SELinux: initialized (dev vda1, type ext4), uses xattr >> [ 7.268037] audit: type=1403 audit(1413877348.283:3): policy loaded auid=4294967295 ses=4294967295 >> [ 7.271168] systemd[1]: Successfully loaded SELinux policy in 180.914ms. >> [ 7.388068] systemd[1]: Relabelled /dev and /run in 13.488ms. >> [ 7.569808] systemd-fstab-generator[336]: Failed to create mount unit file /run/systemd/generator/-.mount, as it already exists. Duplicate entry in /etc/fstab? >> [ 9.794388] SELinux: initialized (dev autofs, type autofs), uses genfs_contexts >> [ 9.800800] SELinux: initialized (dev hugetlbfs, type hugetlbfs), uses transition SIDs >> [ 9.986207] systemd-journald[376]: Vacuuming done, freed 0 bytes >> [ 10.425178] systemd-udevd[386]: starting version 208 >> [ 10.875906] EXT4-fs (vda1): re-mounted. Opts: (null) >> [ 10.876059] EXT4-fs (vda1): re-mounted. Opts: (null) >> [ 11.326782] RPC: Registered named UNIX socket transport module. >> [ 11.326786] RPC: Registered udp transport module. >> [ 11.326787] RPC: Registered tcp transport module. >> [ 11.326788] RPC: Registered tcp NFSv4.1 backchannel transport module. >> [ 11.327476] SELinux: initialized (dev rpc_pipefs, type rpc_pipefs), uses genfs_contexts >> [ 11.598578] input: PC Speaker as /devices/platform/pcspkr/input/input5 >> [ 11.853365] ppdev: user-space parallel port driver >> [ 11.905215] virtio-pci 0000:00:05.0: irq 29 for MSI/MSI-X >> [ 11.905243] virtio-pci 0000:00:05.0: irq 30 for MSI/MSI-X >> [ 12.125787] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0 >> [ 12.145622] device-mapper: uevent: version 1.0.3 >> [ 12.145859] device-mapper: ioctl: 4.27.0-ioctl (2013-10-30) initialised: dm-devel@redhat.com >> [ 12.907774] systemd-journald[376]: Received request to flush runtime journal from PID 1 >> [ 12.909274] Installing knfsd (copyright (C) 1996 okir@monad.swb.de). >> [ 12.909939] SELinux: initialized (dev nfsd, type nfsd), uses genfs_contexts >> [ 13.508495] audit: type=1305 audit(1413877354.523:4): audit_pid=464 old=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:auditd_t:s0 res=1 >> [ 14.534154] snd_hda_intel 0000:00:04.0: irq 31 for MSI/MSI-X >> [ 15.807689] sound hdaudioC0D0: autoconfig: line_outs=1 (0x3/0x0/0x0/0x0/0x0) type:line >> [ 15.807693] sound hdaudioC0D0: speaker_outs=0 (0x0/0x0/0x0/0x0/0x0) >> [ 15.807694] sound hdaudioC0D0: hp_outs=0 (0x0/0x0/0x0/0x0/0x0) >> [ 15.807695] sound hdaudioC0D0: mono: mono_out=0x0 >> [ 15.807696] sound hdaudioC0D0: inputs: >> [ 15.807698] sound hdaudioC0D0: Line=0x5 >> [ 16.057312] sd 0:0:0:0: Attached scsi generic sg0 type 0 >> [ 16.057350] sr 1:0:0:0: Attached scsi generic sg1 type 5 >> [ 20.574760] ip_tables: (C) 2000-2006 Netfilter Core Team >> [ 20.778209] nf_conntrack version 0.5.0 (16384 buckets, 65536 max) >> [ 20.987215] ip6_tables: (C) 2000-2006 Netfilter Core Team >> [ 21.553523] Ebtables v2.0 registered >> [ 21.944469] Bridge firewalling registered >> [ 23.491468] cfg80211: Calling CRDA to update world regulatory domain >> [ 23.517907] cfg80211: World regulatory domain updated: >> [ 23.517909] cfg80211: DFS Master region: unset >> [ 23.517910] cfg80211: (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp), (dfs_cac_time) >> [ 23.517912] cfg80211: (2402000 KHz - 2472000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) >> [ 23.517914] cfg80211: (2457000 KHz - 2482000 KHz @ 40000 KHz), (N/A, 2000 mBm), (N/A) >> [ 23.517915] cfg80211: (2474000 KHz - 2494000 KHz @ 20000 KHz), (N/A, 2000 mBm), (N/A) >> [ 23.517916] cfg80211: (5170000 KHz - 5250000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) >> [ 23.517917] cfg80211: (5735000 KHz - 5835000 KHz @ 80000 KHz), (N/A, 2000 mBm), (N/A) >> [ 23.517918] cfg80211: (57240000 KHz - 63720000 KHz @ 2160000 KHz), (N/A, 0 mBm), (N/A) >> [ 32.318308] snd_hda_intel 0000:00:04.0: Invalid position buffer, using LPIB read method instead. >> [ 32.484087] snd_hda_intel 0000:00:04.0: IRQ timing workaround is activated for card #0. Suggest a bigger bdl_pos_adj. >> [ 120.475365] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: >> [ 120.475562] SELinux: initialized (dev sda1, type ext4), uses mountpoint labeling >> [ 124.510163] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: acl,user_xattr >> [ 124.510341] SELinux: initialized (dev sda2, type ext4), uses mountpoint labeling >> [ 125.618362] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: acl,user_xattr >> [ 125.618369] SELinux: initialized (dev sda1, type ext4), uses xattr >> [ 130.084150] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: acl,user_xattr >> [ 130.084233] SELinux: initialized (dev sda2, type ext4), uses mountpoint labeling >> [ 136.050773] dump_buffers ino:12 page:ffffea00000b4a80 idx:2740 fl:1fffff8000087d >> [ 136.050777] bh:ffff8800783401a0 count:0 state:1023 >> [ 136.087165] dump_buffers ino:12 page:ffffea00000b4a80 idx:2740 fl:1fffff8000087d >> [ 136.087167] bh:ffff8800783401a0 count:0 state:1023 >> [ 136.140289] dump_buffers ino:12 page:ffffea00000b4a80 idx:2740 fl:1fffff8000087d >> [ 136.140294] bh:ffff8800783401a0 count:0 state:1023 >> [ 136.177953] dump_buffers ino:12 page:ffffea00000b4a80 idx:2740 fl:1fffff8000087d >> [ 136.177957] bh:ffff8800783401a0 count:0 state:1023 >> [ 136.214371] dump_buffers ino:12 page:ffffea00000b4a80 idx:2740 fl:1fffff8000087d >> [ 136.214374] bh:ffff8800783401a0 count:0 state:1023 >> [ 208.162760] EXT4-fs (sda2): mounted filesystem with ordered data mode. Opts: acl,user_xattr >> [ 208.162768] SELinux: initialized (dev sda2, type ext4), uses xattr