Re: [PATCH] ext4: move_extent explicitly invalidate page buffers

[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]

 



Hi,

On 01/13/2015 09:32 PM, Dmitry Monakhov wrote:
> Xiaoguang Wang <wangxg.fnst@xxxxxxxxxxxxxx> writes:
> 
>> Hi,
>>
>> On 12/10/2014 06:25 PM, Dmitry Monakhov wrote:
>>> Xiaoguang Wang <wangxg.fnst@xxxxxxxxxxxxxx> writes:
>>>
>>>> Hi,
>>>>
>>>> On 10/23/2014 09:03 PM, Dmitry Monakhov wrote:
>>>>> Dmitry Monakhov <dmonakhov@xxxxxxxxxx> 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.
>>> So I'll change xfstests to ignore EBUSY similar to ENOSPC.
>>> I'll be back with the patches.
>> I spent some time to look into this case again, trying to figure out how it fails and
>> how to fix it. Here is my conclusion, please have a check.
>> As we know, there are 3 different data modes: writeback, ordered and journal, and
>> enableing journal would disable delayed allocation and O_DIRECT support. So I think
>> indeed we have 5 choices:
>>   ordered mode with delalloc
>>   ordered mode with nodelalloc
>>   writeback mode with delalloc
>>   writeback mode with nodelalloc
>>   journal mode.
>>
>> When 302 test case fails to me, its data mode is ordered mode with delalloc. I think the
>> reason is that:
>>     ext4/302 test case is ext4 defragmentation stress test, which performs defragmentation
>>     on file under buffered io while third task does direct io to donor file.
>>
>>     1, this test case first uses fio to create a file(using fallocate()), so this file's
>>        corresponding extents' status will be unwritten.
>>     2, When one process writes to this file, because delalloc is enabled and corresponding
>>        extent status is unwritten, ext4_ext_convert_to_initialized() won't also be called,
>>        data buffer will not be managed by jbd2, and its status would be BH_Uptodate, BH_Dirty,
>>        BH_Mapped and BH_Unwritten.
>>     3. When another process does ioctl(EXT4_IOC_MOVE_EXT) against this file with a donor file,
>>        the below code: in move_extent_per_page() in fs/ext4/move_extent.c.
>>
>>        if (unwritten) {
>> 		...
>> 		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;
>> 			goto drop_data_sem;
>> 		}
>> 		...
>> 	}
>>
>> 	Here the try_to_release_page(pagep[0], 0) will surely return 0. Some buffers in this
>>         page is not managed by jbd2, and is dirty. I think it will wait that mm writeback
>>         subsystem to write back this page, every 30 seconds? So we will get a EBUSY error.
>>         Meanwhile for this EBUSY error, I think this code in move_extent_per_page() won't make
>>         much sense(As it's not managed by jbd2):
>>         	if (*err == -EBUSY && retries++ < 4 && EXT4_SB(sb)->s_journal &&
>> 	    	    jbd2_journal_force_commit_nested(EXT4_SB(sb)->s_journal))
> page can be busy due to JBD or because it is dirty, so force_commit is
> reasonable, and as you already said it does not helps if page was dirty
> in order to fix that we have to force writeback for that region, but it
> can be redirtied again, so we can not avoid EBUSY completely

Right, I see, still the page can be dirtied again.
>> 			goto again;
>>        
>> As Dmitry has said, sane users should call fsync before move_extents, but 302 test case does not do that.
>> So as a workaround, I'd like to have 302 test case run in ordered mode with nodelalloc, this case
>> will starts to succeed in my test environment.
>>
>> Ted and Dmitry, would you please have a check my analysis, if you agree so, I'll send patches to
>> fix 302 case :) And I think 303 fails to me because of the same reason, thanks!
> We can change 302 and 303 to simply ignore EBUSY, IMHO this acceptable
> since this is hard-core stress test.

OK, I'll send this path, and cc you, thanks for your patience!

Regards,
Xiaoguang Wang
>>
>> Regards,
>> Xiaoguang Wang
>>
>>>> I attached a log, which I once sent to you, thanks!
>>>>
>>>> Regards,
>>>> Xiaoguang Wang
>>>>
>>>>>>
>>>>>> Tested-by: Xiaoguang Wang<wangxg.fnst@xxxxxxxxxxxxxx>
>>>>>> Signed-off-by: Dmitry Monakhov <dmonakhov@xxxxxxxxxx>
>>>>>> ---
>>>>>>  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@xxxxxxxxxxxxxxx
>>>>>> More majordomo info at  http://vger.kernel.org/majordomo-info.html
>>>>
>>>> >From a94dae03e2064548c2532ba7786a6cbc29b7bbc9 Mon Sep 17 00:00:00 2001
>>>> From: Dmitry Monakhov <dmonakhov@xxxxxxxxxx>
>>>> Date: Fri, 17 Oct 2014 11:24:37 +0400
>>>> Subject: [PATCH] patch bh-debug.patch
>>>>
>>>>
>>>> Signed-off-by: Dmitry Monakhov <dmonakhov@xxxxxxxxxx>
>>>> ---
>>>>  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 <tigran@xxxxxxxxxxxxxxxxxxxx>, 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 <localhost.localdomain>.
>>>> [    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@xxxxxxxxxx
>>>> [   12.907774] systemd-journald[376]: Received request to flush runtime journal from PID 1
>>>> [   12.909274] Installing knfsd (copyright (C) 1996 okir@xxxxxxxxxxxx).
>>>> [   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
>>
>> --
>> To unsubscribe from this list: send the line "unsubscribe linux-ext4" in
>> the body of a message to majordomo@xxxxxxxxxxxxxxx
>> More majordomo info at  http://vger.kernel.org/majordomo-info.html

--
To unsubscribe from this list: send the line "unsubscribe linux-ext4" in
the body of a message to majordomo@xxxxxxxxxxxxxxx
More majordomo info at  http://vger.kernel.org/majordomo-info.html



[Index of Archives]     [Reiser Filesystem Development]     [Ceph FS]     [Kernel Newbies]     [Security]     [Netfilter]     [Bugtraq]     [Linux FS]     [Yosemite National Park]     [MIPS Linux]     [ARM Linux]     [Linux Security]     [Linux RAID]     [Samba]     [Device Mapper]     [Linux Media]

  Powered by Linux