All of lore.kernel.org
 help / color / mirror / Atom feed
* Bootup regression introduced by 7bd0b0f0da3b1ec11cbcc798eb0ef747a1184077 ("memblock: Reimplement memblock allocation using reverse free area iterato") in v3.3-rc0
@ 2012-01-10 20:28 Konrad Rzeszutek Wilk
  2012-01-10 22:26 ` Tejun Heo
  0 siblings, 1 reply; 10+ messages in thread
From: Konrad Rzeszutek Wilk @ 2012-01-10 20:28 UTC (permalink / raw)
  To: tj, linux-kernel, rjw; +Cc: xen-devel

Hey,

With that patch I get this when trying to launch a 4GB xen guest:

kernel="/mnt/lab/latest/vmlinuz"
ramdisk="/mnt/lab/latest/initramfs.cpio.gz"
extra="console=hvc0 debug earlyprintk=xen" 
memory=4096
maxmem=8192
vcpus=4
on_crash="preserve"
vif = [ 'mac=00:0F:4B:00:00:68, bridge=switch' ]
vfb = [ 'vnc=1, vnclisten=0.0.0.0,vncunused=1'

If I change the "memory" to be "4000" it or if I revert the
mention git commit it boots. This is what I get with the mentioned
git commit:

(early) [    0.000000] Initializing cgroup subsys cpuset
(early) [    0.000000] Initializing cgroup subsys cpu
(early) [    0.000000] Linux version 3.2.0-04371-g6b3da11 (konrad@phenom.dumpdata.com) (gcc version 4.4.4 20100503 (Red Hat 4.4.4-2) (GCC) ) #2 SMP PREEMPT Tue Jan 10 12:47:50 EST 2012
(early) [    0.000000] Command line: console=hvc0 debug earlyprintk=xen
(early) [    0.000000] Disabled fast string operations
(early) [    0.000000] ACPI in unprivileged domain disabled
(early) [    0.000000] Released 0 pages of unused memory
(early) [    0.000000] Set 0 page(s) to 1-1 mapping
(early) [    0.000000] BIOS-provided physical RAM map:
(early) [    0.000000]  Xen: 0000000000000000 - 00000000000a0000 (usable)
(early) [    0.000000]  Xen: 00000000000a0000 - 0000000000100000 (reserved)
(early) [    0.000000]  Xen: 0000000000100000 - 0000000200800000 (usable)
(early) [    0.000000] bootconsole [xenboot0] enabled
(early) [    0.000000] NX (Execute Disable) protection: active
(early) [    0.000000] DMI not present or invalid.
(early) [    0.000000] e820 update range: 0000000000000000 - 0000000000010000 (early) (usable)(early)  ==> (early) (reserved)(early) 
(early) [    0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (early) (usable)(early) 
(early) [    0.000000] No AGP bridge found
(early) [    0.000000] last_pfn = 0x200800 max_arch_pfn = 0x400000000
(early) [    0.000000] last_pfn = 0x100000 max_arch_pfn = 0x400000000
(early) [    0.000000] initial memory mapped : 0 - 0fbec000
(early) [    0.000000] Base memory trampoline at [ffff88000009b000] 9b000 size 20480
(early) [    0.000000] init_memory_mapping: 0000000000000000-0000000100000000
(early) [    0.000000]  0000000000 - 0100000000 page 4k
(early) [    0.000000] Kernel panic - not syncing: Cannot find space for the kernel page tables
(early) [    0.000000] Pid: 0, comm: swapper Not tainted 3.2.0-04371-g6b3da11 #2
(early) [    0.000000] Call Trace:
(early) [    0.000000]  [<ffffffff8163f230>] panic+0x9b/0x1c9
(early) [    0.000000]  [<ffffffff8163f39f>] ? printk+0x41/0x43
(early) [    0.000000]  [<ffffffff81621462>] init_memory_mapping+0x562/0x590
(early) [    0.000000]  [<ffffffff81af58cb>] setup_arch+0x63a/0xafb
(early) [    0.000000]  [<ffffffff8163f39f>] ? printk+0x41/0x43
(early) [    0.000000]  [<ffffffff81aefbaf>] start_kernel+0xe6/0x408
(early) [    0.000000]  [<ffffffff81aef346>] x86_64_start_reservations+0x131/0x136
(early) [    0.000000]  [<ffffffff81af2b62>] xen_start_kernel+0x60d/0x614


^ permalink raw reply	[flat|nested] 10+ messages in thread

* Re: Bootup regression introduced by 7bd0b0f0da3b1ec11cbcc798eb0ef747a1184077 ("memblock: Reimplement memblock allocation using reverse free area iterato") in v3.3-rc0
  2012-01-10 20:28 Bootup regression introduced by 7bd0b0f0da3b1ec11cbcc798eb0ef747a1184077 ("memblock: Reimplement memblock allocation using reverse free area iterato") in v3.3-rc0 Konrad Rzeszutek Wilk
@ 2012-01-10 22:26 ` Tejun Heo
  2012-01-10 22:45   ` Konrad Rzeszutek Wilk
  0 siblings, 1 reply; 10+ messages in thread
From: Tejun Heo @ 2012-01-10 22:26 UTC (permalink / raw)
  To: Konrad Rzeszutek Wilk; +Cc: linux-kernel, rjw, xen-devel

On Tue, Jan 10, 2012 at 03:28:38PM -0500, Konrad Rzeszutek Wilk wrote:
> With that patch I get this when trying to launch a 4GB xen guest:
> 
> kernel="/mnt/lab/latest/vmlinuz"
> ramdisk="/mnt/lab/latest/initramfs.cpio.gz"
> extra="console=hvc0 debug earlyprintk=xen" 
> memory=4096
> maxmem=8192
> vcpus=4
> on_crash="preserve"
> vif = [ 'mac=00:0F:4B:00:00:68, bridge=switch' ]
> vfb = [ 'vnc=1, vnclisten=0.0.0.0,vncunused=1'
> 
> If I change the "memory" to be "4000" it or if I revert the
> mention git commit it boots. This is what I get with the mentioned
> git commit:

Heh, interesting.  Can you please apply the following patch and report
the failing boot log?

Thanks.

diff --git a/mm/memblock.c b/mm/memblock.c
index 2f55f19..978b58eff 100644
--- a/mm/memblock.c
+++ b/mm/memblock.c
@@ -102,6 +102,10 @@ phys_addr_t __init_memblock memblock_find_in_range_node(phys_addr_t start,
 	/* align @size to avoid excessive fragmentation on reserved array */
 	size = round_up(size, align);
 
+	printk("memblock_find: [0x%llx, 0x%llx) size=%llu align=%llu nid=%d\n",
+	       (unsigned long long)start, (unsigned long long)end,
+	       (unsigned long long)size, (unsigned long long)align, nid);
+
 	/* pump up @end */
 	if (end == MEMBLOCK_ALLOC_ACCESSIBLE)
 		end = memblock.current_limit;
@@ -110,13 +114,27 @@ phys_addr_t __init_memblock memblock_find_in_range_node(phys_addr_t start,
 	start = max3(start, size, (phys_addr_t)PAGE_SIZE);
 	end = max(start, end);
 
+	printk("memblock_find: [0x%llx, 0x%llx) - adjusted\n",
+	       (unsigned long long)start, (unsigned long long)end);
+
 	for_each_free_mem_range_reverse(i, nid, &this_start, &this_end, NULL) {
+		printk("memblock_find: cand [0x%llx, 0x%llx) -> ",
+		       (unsigned long long)this_start,
+		       (unsigned long long)this_end);
+
 		this_start = clamp(this_start, start, end);
 		this_end = clamp(this_end, start, end);
 
+		printk("[0x%llx, 0x%llx) ",
+		       (unsigned long long)this_start,
+		       (unsigned long long)this_end);
+
 		cand = round_down(this_end - size, align);
-		if (cand >= this_start)
+		if (cand >= this_start) {
+			printk("- success %llx\n", (unsigned long long)cand);
 			return cand;
+		}
+		printk("- rejected\n");
 	}
 	return 0;
 }

^ permalink raw reply related	[flat|nested] 10+ messages in thread

* Re: Bootup regression introduced by 7bd0b0f0da3b1ec11cbcc798eb0ef747a1184077 ("memblock: Reimplement memblock allocation using reverse free area iterato") in v3.3-rc0
  2012-01-10 22:26 ` Tejun Heo
@ 2012-01-10 22:45   ` Konrad Rzeszutek Wilk
  2012-01-10 23:15     ` Tejun Heo
  0 siblings, 1 reply; 10+ messages in thread
From: Konrad Rzeszutek Wilk @ 2012-01-10 22:45 UTC (permalink / raw)
  To: Tejun Heo; +Cc: linux-kernel, rjw, xen-devel

On Tue, Jan 10, 2012 at 02:26:25PM -0800, Tejun Heo wrote:
> On Tue, Jan 10, 2012 at 03:28:38PM -0500, Konrad Rzeszutek Wilk wrote:
> > With that patch I get this when trying to launch a 4GB xen guest:
> > 
> > kernel="/mnt/lab/latest/vmlinuz"
> > ramdisk="/mnt/lab/latest/initramfs.cpio.gz"
> > extra="console=hvc0 debug earlyprintk=xen" 
> > memory=4096
> > maxmem=8192
> > vcpus=4
> > on_crash="preserve"
> > vif = [ 'mac=00:0F:4B:00:00:68, bridge=switch' ]
> > vfb = [ 'vnc=1, vnclisten=0.0.0.0,vncunused=1'
> > 
> > If I change the "memory" to be "4000" it or if I revert the
> > mention git commit it boots. This is what I get with the mentioned
> > git commit:
> 
> Heh, interesting.  Can you please apply the following patch and report
> the failing boot log?
> 
> Thanks.
> 
> diff --git a/mm/memblock.c b/mm/memblock.c
> index 2f55f19..978b58eff 100644
> --- a/mm/memblock.c
> +++ b/mm/memblock.c
> @@ -102,6 +102,10 @@ phys_addr_t __init_memblock memblock_find_in_range_node(phys_addr_t start,
>  	/* align @size to avoid excessive fragmentation on reserved array */
>  	size = round_up(size, align);
>  
> +	printk("memblock_find: [0x%llx, 0x%llx) size=%llu align=%llu nid=%d\n",
> +	       (unsigned long long)start, (unsigned long long)end,
> +	       (unsigned long long)size, (unsigned long long)align, nid);
> +
>  	/* pump up @end */
>  	if (end == MEMBLOCK_ALLOC_ACCESSIBLE)
>  		end = memblock.current_limit;
> @@ -110,13 +114,27 @@ phys_addr_t __init_memblock memblock_find_in_range_node(phys_addr_t start,
>  	start = max3(start, size, (phys_addr_t)PAGE_SIZE);
>  	end = max(start, end);
>  
> +	printk("memblock_find: [0x%llx, 0x%llx) - adjusted\n",
> +	       (unsigned long long)start, (unsigned long long)end);
> +
>  	for_each_free_mem_range_reverse(i, nid, &this_start, &this_end, NULL) {
> +		printk("memblock_find: cand [0x%llx, 0x%llx) -> ",
> +		       (unsigned long long)this_start,
> +		       (unsigned long long)this_end);
> +
>  		this_start = clamp(this_start, start, end);
>  		this_end = clamp(this_end, start, end);
>  
> +		printk("[0x%llx, 0x%llx) ",
> +		       (unsigned long long)this_start,
> +		       (unsigned long long)this_end);
> +
>  		cand = round_down(this_end - size, align);
> -		if (cand >= this_start)
> +		if (cand >= this_start) {
> +			printk("- success %llx\n", (unsigned long long)cand);
>  			return cand;
> +		}
> +		printk("- rejected\n");
>  	}
>  	return 0;
>  }

Using config file "/mnt/lab/latest/pv.xm".
Started domain latest (id=28)
(early) [    0.000000] Initializing cgroup subsys cpuset
(early) [    0.000000] Initializing cgroup subsys cpu
(early) [    0.000000] Linux version 3.2.0-05808-g597987c (konrad@build.dumpdata.com) (gcc version 4.4.4 20100503 (Red Hat 4.4.4-2) (GCC) ) #1 SMP PREEMPT Tue Jan 10 17:31:15 EST 2012
(early) [    0.000000] Command line: console=hvc0 debug earlyprintk=xenboot memblock=debug loglevel=8
(early) [    0.000000] ACPI in unprivileged domain disabled
(early) [    0.000000] Released 0 pages of unused memory
(early) [    0.000000] Set 0 page(s) to 1-1 mapping
(early) [    0.000000] BIOS-provided physical RAM map:
(early) [    0.000000]  Xen: 0000000000000000 - 00000000000a0000 (usable)
(early) [    0.000000]  Xen: 00000000000a0000 - 0000000000100000 (reserved)
(early) [    0.000000]  Xen: 0000000000100000 - 0000000200800000 (usable)
(early) [    0.000000] bootconsole [xenboot0] enabled
(early) [    0.000000] NX (Execute Disable) protection: active
(early) [    0.000000] DMI not present or invalid.
(early) [    0.000000] e820 update range: 0000000000000000 - 0000000000010000 (early) (usable)(early)  ==> (early) (reserved)(early) 
(early) [    0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (early) (usable)(early) 
(early) [    0.000000] No AGP bridge found
(early) [    0.000000] last_pfn = 0x200800 max_arch_pfn = 0x400000000
(early) [    0.000000] last_pfn = 0x100000 max_arch_pfn = 0x400000000
(early) [    0.000000] memblock_reserve: [0x00000001dec000-0x00000001e03000] setup_arch+0x592/0xafb
(early) [    0.000000] MEMBLOCK configuration:
(early) [    0.000000]  memory size = 0x200790000 reserved size = 0x10f960000
(early) [    0.000000]  memory.cnt  = 0x2
(early) [    0.000000]  memory[0x0]	[0x00000000010000-0x0000000009ffff], 0x90000 bytes
(early) [    0.000000]  memory[0x1]	[0x00000000100000-0x000002007fffff], 0x200700000 bytes
(early) [    0.000000]  reserved.cnt  = 0x3
(early) [    0.000000]  reserved[0x0]	[0x00000001000000-0x00000001e02fff], 0xe03000 bytes
(early) [    0.000000]  reserved[0x1]	[0x0000000220a000-0x00000010566fff], 0xe35d000 bytes
(early) [    0.000000]  reserved[0x2]	[0x00000100000000-0x000002007fffff], 0x100800000 bytes
(early) [    0.000000] initial memory mapped : 0 - 0fcdd000
(early) [    0.000000] memblock_find: [0x0, 0x100000) size=20480 align=4096 nid=1024
(early) [    0.000000] memblock_find: [0x5000, 0x100000) - adjusted
(early) [    0.000000] memblock_find: cand [0x10567000, 0x100000000) -> (early) [0x100000, 0x100000) (early) - rejected
(early) [    0.000000] memblock_find: cand [0x1e03000, 0x220a000) -> (early) [0x100000, 0x100000) (early) - rejected
(early) [    0.000000] memblock_find: cand [0x100000, 0x1000000) -> (early) [0x100000, 0x100000) (early) - rejected
(early) [    0.000000] memblock_find: cand [0x10000, 0xa0000) -> (early) [0x10000, 0xa0000) (early) - success 9b000
(early) [    0.000000] memblock_reserve: [0x0000000009b000-0x000000000a0000] setup_trampolines+0x66/0x9c
(early) [    0.000000] Base memory trampoline at [ffff88000009b000] 9b000 size 20480
(early) [    0.000000] init_memory_mapping: 0000000000000000-0000000100000000
(early) [    0.000000]  0000000000 - 0100000000 page 4k
(early) [    0.000000] memblock_find: [0x0, 0xfcdd000) size=8409088 align=4096 nid=1024
(early) [    0.000000] memblock_find: [0x805000, 0xfcdd000) - adjusted
(early) [    0.000000] memblock_find: cand [0x10567000, 0x100000000) -> (early) [0xfcdd000, 0xfcdd000) (early) - rejected
(early) [    0.000000] memblock_find: cand [0x1e03000, 0x220a000) -> (early) [0x1e03000, 0x220a000) (early) - rejected
(early) [    0.000000] memblock_find: cand [0x100000, 0x1000000) -> (early) [0x805000, 0x1000000) (early) - rejected
(early) [    0.000000] memblock_find: cand [0x10000, 0x9b000) -> (early) [0x805000, 0x805000) (early) - rejected
(early) [    0.000000] Kernel panic - not syncing: Cannot find space for the kernel page tables
(early) [    0.000000] Pid: 0, comm: swapper Not tainted 3.2.0-05808-g597987c #1
(early) [    0.000000] Call Trace:
(early) [    0.000000]  [<ffffffff816011e5>] panic+0x96/0x1c4
(early) [    0.000000]  [<ffffffff815e4612>] init_memory_mapping+0x562/0x590
(early) [    0.000000]  [<ffffffff81ae88c6>] setup_arch+0x63a/0xafb
(early) [    0.000000]  [<ffffffff8160134f>] ? printk+0x3c/0x3e
(early) [    0.000000]  [<ffffffff81ae2baf>] start_kernel+0xe6/0x403
(early) [    0.000000]  [<ffffffff81ae2346>] x86_64_start_reservations+0x131/0x136
(early) [    0.000000]  [<ffffffff81ae5b5d>] xen_start_kernel+0x60d/0x614

^ permalink raw reply	[flat|nested] 10+ messages in thread

* Re: Bootup regression introduced by 7bd0b0f0da3b1ec11cbcc798eb0ef747a1184077 ("memblock: Reimplement memblock allocation using reverse free area iterato") in v3.3-rc0
  2012-01-10 22:45   ` Konrad Rzeszutek Wilk
@ 2012-01-10 23:15     ` Tejun Heo
  2012-01-11 20:04       ` Konrad Rzeszutek Wilk
  0 siblings, 1 reply; 10+ messages in thread
From: Tejun Heo @ 2012-01-10 23:15 UTC (permalink / raw)
  To: Konrad Rzeszutek Wilk; +Cc: linux-kernel, rjw, xen-devel

Hello,

On Tue, Jan 10, 2012 at 05:45:37PM -0500, Konrad Rzeszutek Wilk wrote:
> (early) [    0.000000] memblock_find: [0x0, 0xfcdd000) size=8409088 align=4096 nid=1024
> (early) [    0.000000] memblock_find: [0x805000, 0xfcdd000) - adjusted
> (early) [    0.000000] memblock_find: cand [0x10567000, 0x100000000) -> (early) [0xfcdd000, 0xfcdd000) (early) - rejected
> (early) [    0.000000] memblock_find: cand [0x1e03000, 0x220a000) -> (early) [0x1e03000, 0x220a000) (early) - rejected
> (early) [    0.000000] memblock_find: cand [0x100000, 0x1000000) -> (early) [0x805000, 0x1000000) (early) - rejected
> (early) [    0.000000] memblock_find: cand [0x10000, 0x9b000) -> (early) [0x805000, 0x805000) (early) - rejected
> (early) [    0.000000] Kernel panic - not syncing: Cannot find space for the kernel page tables

So, it actually is a legitimate alloc failure.  It seems I've tried a
bit too hard at simplifying the allocator.  Does the following fix the
problem?

Thanks.

diff --git a/mm/memblock.c b/mm/memblock.c
index 2f55f19..77b5f22 100644
--- a/mm/memblock.c
+++ b/mm/memblock.c
@@ -106,14 +106,17 @@ phys_addr_t __init_memblock memblock_find_in_range_node(phys_addr_t start,
 	if (end == MEMBLOCK_ALLOC_ACCESSIBLE)
 		end = memblock.current_limit;
 
-	/* adjust @start to avoid underflow and allocating the first page */
-	start = max3(start, size, (phys_addr_t)PAGE_SIZE);
+	/* avoid allocating the first page */
+	start = max_t(phys_addr_t, start, PAGE_SIZE);
 	end = max(start, end);
 
 	for_each_free_mem_range_reverse(i, nid, &this_start, &this_end, NULL) {
 		this_start = clamp(this_start, start, end);
 		this_end = clamp(this_end, start, end);
 
+		if (this_end < size)
+			continue;
+
 		cand = round_down(this_end - size, align);
 		if (cand >= this_start)
 			return cand;


^ permalink raw reply related	[flat|nested] 10+ messages in thread

* Re: Bootup regression introduced by 7bd0b0f0da3b1ec11cbcc798eb0ef747a1184077 ("memblock: Reimplement memblock allocation using reverse free area iterato") in v3.3-rc0
  2012-01-10 23:15     ` Tejun Heo
@ 2012-01-11 20:04       ` Konrad Rzeszutek Wilk
  2012-01-13 14:27         ` Konrad Rzeszutek Wilk
  0 siblings, 1 reply; 10+ messages in thread
From: Konrad Rzeszutek Wilk @ 2012-01-11 20:04 UTC (permalink / raw)
  To: Tejun Heo; +Cc: linux-kernel, rjw, xen-devel

On Tue, Jan 10, 2012 at 03:15:52PM -0800, Tejun Heo wrote:
> Hello,
> 
> On Tue, Jan 10, 2012 at 05:45:37PM -0500, Konrad Rzeszutek Wilk wrote:
> > (early) [    0.000000] memblock_find: [0x0, 0xfcdd000) size=8409088 align=4096 nid=1024
> > (early) [    0.000000] memblock_find: [0x805000, 0xfcdd000) - adjusted
> > (early) [    0.000000] memblock_find: cand [0x10567000, 0x100000000) -> (early) [0xfcdd000, 0xfcdd000) (early) - rejected
> > (early) [    0.000000] memblock_find: cand [0x1e03000, 0x220a000) -> (early) [0x1e03000, 0x220a000) (early) - rejected
> > (early) [    0.000000] memblock_find: cand [0x100000, 0x1000000) -> (early) [0x805000, 0x1000000) (early) - rejected
> > (early) [    0.000000] memblock_find: cand [0x10000, 0x9b000) -> (early) [0x805000, 0x805000) (early) - rejected
> > (early) [    0.000000] Kernel panic - not syncing: Cannot find space for the kernel page tables
> 
> So, it actually is a legitimate alloc failure.  It seems I've tried a
> bit too hard at simplifying the allocator.  Does the following fix the
> problem?
> 
> Thanks.
> 
> diff --git a/mm/memblock.c b/mm/memblock.c
> index 2f55f19..77b5f22 100644
> --- a/mm/memblock.c
> +++ b/mm/memblock.c
> @@ -106,14 +106,17 @@ phys_addr_t __init_memblock memblock_find_in_range_node(phys_addr_t start,
>  	if (end == MEMBLOCK_ALLOC_ACCESSIBLE)
>  		end = memblock.current_limit;
>  
> -	/* adjust @start to avoid underflow and allocating the first page */
> -	start = max3(start, size, (phys_addr_t)PAGE_SIZE);
> +	/* avoid allocating the first page */
> +	start = max_t(phys_addr_t, start, PAGE_SIZE);
>  	end = max(start, end);
>  
>  	for_each_free_mem_range_reverse(i, nid, &this_start, &this_end, NULL) {
>  		this_start = clamp(this_start, start, end);
>  		this_end = clamp(this_end, start, end);
>  
> +		if (this_end < size)
> +			continue;
> +
>  		cand = round_down(this_end - size, align);
>  		if (cand >= this_start)
>  			return cand;

With that patch it boots!


(early) [    0.000000] Initializing cgroup subsys cpuset
(early) [    0.000000] Initializing cgroup subsys cpu
(early) [    0.000000] Linux version 3.2.0-05693-g2c81411 (konrad@phenom.dumpdata.com) (gcc version 4.4.4 20100503 (Red Hat 4.4.4-2) (GCC) ) #1 SMP PREEMPT Wed Jan 11 13:19:30 EST 2012
(early) [    0.000000] Command line: console=hvc0 debug earlyprintk=xen
(early) [    0.000000] ACPI in unprivileged domain disabled
(early) [    0.000000] Released 0 pages of unused memory
(early) [    0.000000] Set 0 page(s) to 1-1 mapping
(early) [    0.000000] BIOS-provided physical RAM map:
(early) [    0.000000]  Xen: 0000000000000000 - 00000000000a0000 (usable)
(early) [    0.000000]  Xen: 00000000000a0000 - 0000000000100000 (reserved)
(early) [    0.000000]  Xen: 0000000000100000 - 0000000200800000 (usable)
(early) [    0.000000] bootconsole [xenboot0] enabled
(early) [    0.000000] NX (Execute Disable) protection: active
(early) [    0.000000] DMI not present or invalid.
(early) [    0.000000] e820 update range: 0000000000000000 - 0000000000010000 (early) (usable)(early)  ==> (early) (reserved)(early) 
(early) [    0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (early) (usable)(early) 
(early) [    0.000000] No AGP bridge found
(early) [    0.000000] last_pfn = 0x200800 max_arch_pfn = 0x400000000
(early) [    0.000000] last_pfn = 0x100000 max_arch_pfn = 0x400000000
(early) [    0.000000] initial memory mapped : 0 - 10006000
(early) [    0.000000] Base memory trampoline at [ffff88000009b000] 9b000 size 20480
(early) [    0.000000] init_memory_mapping: 0000000000000000-0000000100000000
(early) [    0.000000]  0000000000 - 0100000000 page 4k
(early) [    0.000000] kernel direct mapping tables up to 100000000 @ 7fb000-1000000
(early) [    0.000000] xen: setting RW the range f74000 - 1000000
(early) [    0.000000] init_memory_mapping: 0000000100000000-0000000200800000
(early) [    0.000000]  0100000000 - 0200800000 page 4k
(early) [    0.000000] kernel direct mapping tables up to 200800000 @ feff2000-100000000
(early) [    0.000000] xen: setting RW the range ff7fb000 - 100000000
(early) [    0.000000] RAMDISK: 02249000 - 10006000
(early) [    0.000000] No NUMA configuration found
(early) [    0.000000] Faking a node at 0000000000000000-0000000200800000
(early) [    0.000000] Initmem setup node 0 0000000000000000-0000000200800000
(early) [    0.000000]   NODE_DATA [00000000fffd9000 - 00000000ffffffff]
(early) [    0.000000] Zone PFN ranges:
(early) [    0.000000]   DMA      (early) 0x00000010 -> 0x00001000
(early) [    0.000000]   DMA32    (early) 0x00001000 -> 0x00100000
(early) [    0.000000]   Normal   (early) 0x00100000 -> 0x00200800
(early) [    0.000000] Movable zone start PFN for each node
(early) [    0.000000] Early memory PFN ranges
(early) [    0.000000]     0: 0x00000010 -> 0x000000a0
(early) [    0.000000]     0: 0x00000100 -> 0x00200800
(early) [    0.000000] On node 0 totalpages: 2099088
(early) [    0.000000]   DMA zone: 64 pages used for memmap
(early) [    0.000000]   DMA zone: 1918 pages reserved
(early) [    0.000000]   DMA zone: 2002 pages, LIFO batch:0
(early) [    0.000000]   DMA32 zone: 16320 pages used for memmap
(early) [    0.000000]   DMA32 zone: 1028160 pages, LIFO batch:31
(early) [    0.000000]   Normal zone: 16416 pages used for memmap
(early) [    0.000000]   Normal zone: 1034208 pages, LIFO batch:31
(early) [    0.000000] SMP: Allowing 4 CPUs, 0 hotplug CPUs
(early) [    0.000000] No local APIC present
(early) [    0.000000] APIC: disable apic facility
(early) [    0.000000] APIC: switched to apic NOOP
(early) [    0.000000] nr_irqs_gsi: 16
(early) [    0.000000] PM: Registered nosave memory: 00000000000a0000 - 0000000000100000
(early) [    0.000000] PCI: Warning: Cannot find a gap in the 32bit address range
(early) [    0.000000] PCI: Unassigned devices with 32bit resource registers may break!
(early) [    0.000000] Allocating PCI resources starting at 200900000 (gap: 200900000:400000)
(early) [    0.000000] Booting paravirtualized kernel on Xen
(early) [    0.000000] Xen version: 4.1-120111 (preserve-AD)
(early) [    0.000000] setup_percpu: NR_CPUS:4096 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
(early) [    0.000000] PERCPU: Embedded 28 pages/cpu @ffff8800fff31000 s82752 r8192 d23744 u114688
(early) [    0.000000] pcpu-alloc: s82752 r8192 d23744 u114688 alloc=28*4096(early) 
(early) [    0.000000] pcpu-alloc: (early) [0] (early) 0 (early) [0] (early) 1 (early) [0] (early) 2 (early) [0] (early) 3 (early) 
(early) [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 2064370
(early) [    0.000000] Policy zone: Normal
(early) [    0.000000] Kernel command line: console=hvc0 debug earlyprintk=xen
(early) [    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
(early) [    0.000000] Checking aperture...
(early) [    0.000000] No AGP bridge found
(early) [    0.000000] Calgary: detecting Calgary via BIOS EBDA area
(early) [    0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing!
(early) [    0.000000] Memory: 3727344k/8396800k available (6482k kernel code, 448k absent, 4669008k reserved, 4624k data, 1256k init)
(early) [    0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
(early) [    0.000000] Preemptible hierarchical RCU implementation.
(early) [    0.000000] NR_IRQS:262400 nr_irqs:304 16
(early) [    0.000000] kmemleak: Early log buffer exceeded, please increase DEBUG_KMEMLEAK_EARLY_LOG_SIZE
(early) [    0.000000] kmemleak: Kernel memory leak detector disabled
(early) [    0.000000] Console: colour dummy device 80x25
(early) [    0.000000] console [tty0] enabled
[    0.000000] console [hvc0] enabled, bootconsole disabled
(early) [    0.000000] console [hvc0] enabled, bootconsole disabled
[    0.000000] Xen: using vcpuop timer interface
[    0.000000] installing Xen timer for CPU 0
[    0.000000] Detected 3292.616 MHz processor.
[    0.000999] Calibrating delay loop (skipped), value calculated using timer frequency.. 6585.23 BogoMIPS (lpj=3292616)
[    0.000999] pid_max: default: 32768 minimum: 301
[    0.000999] Security Framework initialized
[    0.000999] SELinux:  Initializing.
[    0.000999] SELinux:  Starting in permissive mode
[    0.002170] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes)
[    0.004675] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes)
[    0.005213] Mount-cache hash table entries: 256
[    0.005428] Initializing cgroup subsys cpuacct
[    0.005436] Initializing cgroup subsys freezer
[    0.005497] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.005498] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
[    0.005510] CPU: Physical Processor ID: 0
[    0.005515] CPU: Processor Core ID: 0
[    0.005576] SMP alternatives: switching to UP code
[    0.005999] ftrace: allocating 23453 entries in 92 pages
[    0.006057] cpu 0 spinlock event irq 17
[    0.006112] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only.
[    0.012038] NMI watchdog disabled (cpu0): hardware events not enabled
[    0.018010] installing Xen timer for CPU 1
[    0.018028] cpu 1 spinlock event irq 23
[    0.018051] SMP alternatives: switching to SMP code
[    0.019085] NMI watchdog disabled (cpu1): hardware events not enabled
[    0.025009] installing Xen timer for CPU 2
[    0.025027] cpu 2 spinlock event irq 29
[    0.025121] NMI watchdog disabled (cpu2): hardware events not enabled
[    0.031008] installing Xen timer for CPU 3
[    0.031024] cpu 3 spinlock event irq 35
[    0.031118] NMI watchdog disabled (cpu3): hardware events not enabled
[    0.033006] Brought up 4 CPUs
[    0.033666] kworker/u:0 used greatest stack depth: 5272 bytes left
[    0.033666] Grant tables using version 2 layout.
[    0.033666] Grant table initialized
[    0.052849] RTC time: 165:165:165, date: 165/165/65
[    0.053033] NET: Registered protocol family 16
[    0.054556] PCI: setting up Xen PCI frontend stub
[    0.054565] PCI: pci_cache_line_size set to 64 bytes
[    0.065198] bio: create slab <bio-0> at 0
[    0.065198] ACPI: Interpreter disabled.
[    0.066004] xen/balloon: Initialising balloon driver.
[    0.076187] xen-balloon: Initialising balloon driver.
[    0.076187] vgaarb: loaded
[    0.077064] usbcore: registered new interface driver usbfs
[    0.077067] usbcore: registered new interface driver hub
[    0.077107] usbcore: registered new device driver usb
[    0.077107] PCI: System does not support PCI
[    0.077107] PCI: System does not support PCI
[    0.078054] NetLabel: Initializing
[    0.078054] NetLabel:  domain hash size = 128
[    0.078054] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.078054] NetLabel:  unlabeled traffic allowed by default
[    0.078109] Switching to clocksource xen
[    0.083574] pnp: PnP ACPI: disabled
[    0.089832] PCI: max bus depth: 0 pci_try_num: 1
[    0.089877] NET: Registered protocol family 2
[    0.090665] IP route cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.093957] TCP established hash table entries: 524288 (order: 11, 8388608 bytes)
[    0.095324] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[    0.095466] TCP: Hash tables configured (established 524288 bind 65536)
[    0.095474] TCP reno registered
[    0.095526] UDP hash table entries: 4096 (order: 5, 131072 bytes)
[    0.095597] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes)
[    0.095720] NET: Registered protocol family 1
[    0.095957] RPC: Registered named UNIX socket transport module.
[    0.095965] RPC: Registered udp transport module.
[    0.095970] RPC: Registered tcp transport module.
[    0.095976] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.095984] PCI: CLS 0 bytes, default 64
[    0.096219] Trying to unpack rootfs image as initramfs...
[    0.431565] Freeing initrd memory: 227060k freed
[    0.475737] DMA-API: preallocated 32768 debug entries
[    0.475961] DMA-API: debugging enabled by kernel config
[    0.475969] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[    0.475977] Placing 64MB software IO TLB between ffff8800f2800000 - ffff8800f6800000
[    0.475984] software IO TLB at phys 0xf2800000 - 0xf6800000
[    0.476324] platform rtc_cmos: registered platform RTC device (no PNP device found)
[    0.476472] Machine check injector initialized
[    0.477452] microcode: CPU0 sig=0x206a7, pf=0x2, revision=0x17
[    0.477466] microcode: CPU1 sig=0x206a7, pf=0x2, revision=0x17
[    0.477486] microcode: CPU2 sig=0x206a7, pf=0x2, revision=0x17
[    0.477503] microcode: CPU3 sig=0x206a7, pf=0x2, revision=0x17
[    0.477586] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[    0.478032] audit: initializing netlink socket (disabled)
[    0.478053] type=2000 audit(1326338469.110:1): initialized
[    0.494006] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.499995] VFS: Disk quotas dquot_6.5.2
[    0.500208] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.501045] NTFS driver 2.1.30 [Flags: R/W].
[    0.501365] msgmni has been set to 7723
[    0.501583] SELinux:  Registering netfilter hooks
[    0.502294] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    0.502303] io scheduler noop registered
[    0.502309] io scheduler deadline registered
[    0.502405] io scheduler cfq registered (default)
[    0.502633] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.542586] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.573641] Non-volatile memory driver v1.3
[    0.573649] Linux agpgart interface v0.103
[    0.574083] [drm] Initialized drm 1.1.0 20060810
[    0.577049] brd: module loaded
[    0.578684] loop: module loaded
[    0.579544] Fixed MDIO Bus: probed
[    0.579551] tun: Universal TUN/TAP device driver, 1.6
[    0.579556] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    0.580300] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.580308] ehci_hcd: block sizes: qh 112 qtd 96 itd 192 sitd 96
[    0.580386] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.580392] ohci_hcd: block sizes: ed 80 td 96
[    0.580466] uhci_hcd: USB Universal Host Controller Interface driver
[    0.580701] usbcore: registered new interface driver usblp
[    0.580780] usbcore: registered new interface driver libusual
[    0.581039] i8042: PNP: No PS/2 controller found. Probing ports directly.
[    0.581865] i8042: No controller found
[    0.581951] mousedev: PS/2 mouse device common for all mice
[    0.622367] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0
[    0.622469] rtc_cmos: probe of rtc_cmos failed with error -38
[    0.622730] EFI Variables Facility v0.08 2004-May-17
[    0.622805] zram: num_devices not specified. Using default: 1
[    0.622812] zram: Creating 1 devices ...
[    0.623448] Netfilter messages via NETLINK v0.30.
[    0.623467] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[    0.623780] ctnetlink v0.93: registering with nfnetlink.
[    0.625065] ip_tables: (C) 2000-2006 Netfilter Core Team
[    0.625099] TCP cubic registered
[    0.625105] Initializing XFRM netlink socket
[    0.625468] NET: Registered protocol family 10
[    0.627394] ip6_tables: (C) 2000-2006 Netfilter Core Team
[    0.627485] IPv6 over IPv4 tunneling driver
[    0.629062] NET: Registered protocol family 17
[    0.629097] Registering the dns_resolver key type
[    0.629362] PM: Hibernation image not present or could not be loaded.
[    0.629386] registered taskstats version 1
[    0.629448] XENBUS: Device with no driver: device/vif/0
[    0.629454] XENBUS: Device with no driver: device/vfb/0
[    0.629460] XENBUS: Device with no driver: device/vkbd/0
[    0.629475]   Magic number: 1:252:3141
[    0.630415] Freeing unused kernel memory: 1256k freed
[    0.630622] Write protecting the kernel read-only data: 10240k
[    0.636139] Freeing unused kernel memory: 1688k freed
[    0.636508] Freeing unused kernel memory: 112k freed
init started: BusyBox v1.14.3 (2012-01-11 13:22:11 EST)
[    0.641820] consoletype used greatest stack depth: 5232 bytes left
Mounting directories  [  OK  ]
[    0.860513] modprobe used greatest stack depth: 5008 bytes left
mount: mount point /sys/kernel/config does not exist
[    0.865846] core_filesystem used greatest stack depth: 4880 bytes left
[    0.874814] input: Xen Virtual Keyboard as /devices/virtual/input/input0
[    0.875019] input: Xen Virtual Pointer as /devices/virtual/input/input1
[    1.086237] Initialising Xen virtual ethernet driver.
[    1.201417] udevd (1186): /proc/1186/oom_adj is deprecated, please use /proc/1186/oom_score_adj instead.
udevd-work[1202]: error opening ATTR{/sys/devices/system/cpu/cpu0/online} for writing: No such file or directory

[    1.307448] ip used greatest stack depth: 3696 bytes left
Waiting for devices [  OK  ]
Waiting for fb [  OK  ]
Starting..[/dev/fb0]
/dev/fb0: len:0
/dev/fb0: bits/pixel32
(7fa51499a000): Writting .. [800:600]
Done!
FATAL: Module agpgart_intel not found.
[    1.464693] Console: switching to colour frame buffer device 100x37
[    1.478942] [drm] radeon kernel modesetting enabled.
WARNING: Error inserting video (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/acpi/video.ko): No such device
WARNING: Error inserting mxm_wmi (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/platform/x86/mxm-wmi.ko): No such device
WARNING: Error inserting drm_kms_helper (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/gpu/drm/drm_kms_helper.ko): No such device
WARNING: Error inserting ttm (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/gpu/drm/ttm/ttm.ko): No such device
FATAL: Error inserting nouveau (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/gpu/drm/nouveau/nouveau.ko): No such device
WARNING: Error inserting drm_kms_helper (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/gpu/drm/drm_kms_helper.ko): No such device
FATAL: Error inserting i915 (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/gpu/drm/i915/i915.ko): No such device
Starting..[/dev/fb0]
/dev/fb0: len:0
/dev/fb0: bits/pixel32
(7fc2635c3000): Writting .. [800:600]
Done!
VGA: 0000:
Waiting for network [  OK  ]
Bringing up loopback interface:  [  OK  ]
Bringing up interface eth0:  [    1.743980] device eth0 entered promiscuous mode
[  OK  ]
Bringing up interface switch:  
Determining IP information for switch...[    1.790208] switch: port 1(eth0) entering forwarding state
[    1.790244] switch: port 1(eth0) entering forwarding state
[    1.790880] ip used greatest stack depth: 3376 bytes left
 done.
[  OK  ]
Waiting for init.custom [  OK  ]

Starting SSHd ...

    SSH started [2314]


Waiting for SSHd [  OK  ]
WARNING: ssh currently running [2314] ignoring start request
FATAL: Module dump_dma not found.
ERROR: Module dump_dma does not exist in /proc/modules
[    3.562254] SCSI subsystem initialized
[    3.563911] Loading iSCSI transport class v2.0-870.
[    3.566452] iscsi: registered transport (tcp)
iscsistart: transport class version 2.0-870. iscsid version 2.0-872
Could not get list of targets from firmware.
Jan 12 03:21:12 g-pvops syslogd 1.5.0: restart.
FATAL: Module evtchn not found.
[    3.595640] Event-channel device installed.
xencommons should be started first.
           CPU0       CPU1       CPU2       CPU3       
 16:       1330          0          0          0  xen-percpu-virq      timer0
 17:          6          0          0          0  xen-percpu-ipi       spinlock0
 18:       1872          0          0          0  xen-percpu-ipi       resched0
 19:        129          0          0          0  xen-percpu-ipi       callfunc0
 20:          0          0          0          0  xen-percpu-virq      debug0
 21:         77          0          0          0  xen-percpu-ipi       callfuncsingle0
 22:          0       1811          0          0  xen-percpu-virq      timer1
 23:          0         14          0          0  xen-percpu-ipi       spinlock1
 24:          0       2411          0          0  xen-percpu-ipi       resched1
 25:          0        140          0          0  xen-percpu-ipi       callfunc1
 26:          0          0          0          0  xen-percpu-virq      debug1
 27:          0         78          0          0  xen-percpu-ipi       callfuncsingle1
 28:          0          0       1204          0  xen-percpu-virq      timer2
 29:          0          0          5          0  xen-percpu-ipi       spinlock2
 30:          0          0       3713          0  xen-percpu-ipi       resched2
 31:          0          0        148          0  xen-percpu-ipi       callfunc2
 32:          0          0          0          0  xen-percpu-virq      debug2
 33:          0          0        103          0  xen-percpu-ipi       callfuncsingle2
 34:          0          0          0       1373  xen-percpu-virq      timer3
 35:          0          0          0          5  xen-percpu-ipi       spinlock3
 36:          0          0          0       2474  xen-percpu-ipi       resched3
 37:          0          0          0        131  xen-percpu-ipi       callfunc3
 38:          0          0          0          0  xen-percpu-virq      debug3
 39:          0          0          0         88  xen-percpu-ipi       callfuncsingle3
 40:        423          0          0          0   xen-dyn-event     xenbus
 41:         81          0          0          0   xen-dyn-event     hvc_console
 42:          0          0          0          0   xen-dyn-event     vkbd
 43:         70          0          0          0   xen-dyn-event     vfb
 44:         20          0          0          0   xen-dyn-event     eth0
NMI:          0          0          0          0   Non-maskable interrupts
LOC:          0          0          0          0   Local timer interrupts
SPU:          0          0          0          0   Spurious interrupts
PMI:          0          0          0          0   Performance monitoring interrupts
IWI:          0          0          0          0   IRQ work interrupts
RTR:          0          0          0          0   APIC ICR read retries
RES:       1872       2411       3713       2474   Rescheduling interrupts
CAL:        206        218        251        219   Function call interrupts
TLB:          0          0          0          0   TLB shootdowns
TRM:          0          0          0          0   Thermal event interrupts
THR:          0          0          0          0   Threshold APIC interrupts
MCE:          0          0          0          0   Machine check exceptions
MCP:          0          0          0          0   Machine check polls
ERR:          0
MIS:          0
00000000-0000ffff : reserved
00010000-0009ffff : System RAM
000a0000-000fffff : reserved
  000f0000-000fffff : System ROM
00100000-2007fffff : System RAM
  01000000-016549db : Kernel code
  016549dc-01ad89ff : Kernel data
  01c1a000-01e2afff : Kernel bss
Waiting for init.late [  OK  ]
PING build.dumpdata.com (192.168.101.3) 56(84) bytes of data.

--- build.dumpdata.com ping statistics ---
1 packets transmitted, 1 received, 0% packet loss, time 0ms
rtt min/avg/max/mdev = 0.329/0.329/0.329/0.000 ms
NFS done
 [0x0->0x100000] pfn
 [0x0->0x100000] level entry
 [0x100000->0x7cfffff] missing
 [0x100000->0x7cfffff] level top
libxl: error: libxl.c:56:libxl_ctx_init Is xenstore daemon running?
failed to stat /var/run/xenstored.pid: No such file or directory
cannot init xl context
[    4.481012] device-mapper: ioctl: 4.22.0-ioctl (2011-10-19) initialised: dm-devel@redhat.com
[    4.482152] device-mapper: multipath: version 1.3.0 loaded
192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
Logging in to [iface: default, target: iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb, portal: 192.168.101.2,3260]
Jan 12 03:21:13 g-pvops iscsid: transport class version 2.0-870. iscsid version 2.0-872
Jan 12 03:21:13 g-pvops iscsid: iSCSI daemon with pid=2387 started!
[    4.747529] scsi0 : iSCSI Initiator over TCP/IP
[    4.751948]  connection1:0: detected conn error (1020)
iscsiadm: Could not login to [iface: default, target: iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb, portal: 192.168.101.2,3260].
iscsiadm: initiator reported error (19 - encountered non-retryable iSCSI login failure)
Jan 12 03:21:14 g-pvops iscsid: conn 0 login rejected: initiator failed authorization with target
Jan 12 03:21:14 g-pvops iscsid: Connection1:0 to [target: iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb, portal: 192.168.101.2,3260] through [iface: default] is shutdown.

poweroff
  No matching physical volumes found
  No volume groups found
Jan 12 03:21:18 g-pvops init: starting pid 2466, tty '/dev/tty2': '/bin/sh'
Jan 12 03:21:18 g-pvops init: starting pid 2469, tty '/dev/tty1': '/bin/sh'
Jan 12 03:21:18 g-pvops init: starting pid 2470, tty '/dev/ttyS0': '/bin/sh'
Jan 12 03:21:18 g-pvops init: starting pid 2471, tty '/dev/hvc0': '/bin/sh'

   ~~~~~~~~~~~~~~~~~~~~~~~~~~| DirectFB 1.4.9 |~~~~~~~~~~~~~~~~~~~~~~~~~~
        (c) 2001-2010  The world wide DirectFB Open Source Community
        (c) 2000-2004  Convergence (integrated media) GmbH
      ----------------------------------------------------------------

(*) DirectFB/Core: Single Application Core. (2012-01-11 18:23) 
sh-4.1# 
sh-4.1# poweroff
(*) Direct/Memcpy: Using Generic 64bit memcpy()
Jan 12 03:21:18 g-pvops init: starting pid 2477, tty '': '/etc/init.d/halt'
sh-4.1# Usage: /etc/init.d/halt {start}
The system is going down NOW!
Sent SIGTERM to all processes
(!) [ 2465:    0.000] --> Caught signal 15 (sent by pid 1, uid 0) <--
(!) [ 2465:    0.000] --> Caught signal 11 (at 0x38, invalid address) <--
Sent SIGKILL to all processes
Requesting system poweroff
[   12.086915] System halted.
Parsing config file /mnt/lab/tst018/pv.xm
Daemon running with PID 3972

^ permalink raw reply	[flat|nested] 10+ messages in thread

* Re: Bootup regression introduced by 7bd0b0f0da3b1ec11cbcc798eb0ef747a1184077 ("memblock: Reimplement memblock allocation using reverse free area iterato") in v3.3-rc0
  2012-01-11 20:04       ` Konrad Rzeszutek Wilk
@ 2012-01-13 14:27         ` Konrad Rzeszutek Wilk
  2012-01-13 18:14           ` [PATCH 3.3-rc] memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node() Tejun Heo
  0 siblings, 1 reply; 10+ messages in thread
From: Konrad Rzeszutek Wilk @ 2012-01-13 14:27 UTC (permalink / raw)
  To: Tejun Heo; +Cc: linux-kernel, rjw, xen-devel

On Wed, Jan 11, 2012 at 03:04:35PM -0500, Konrad Rzeszutek Wilk wrote:
> On Tue, Jan 10, 2012 at 03:15:52PM -0800, Tejun Heo wrote:
> > Hello,
> > 
> > On Tue, Jan 10, 2012 at 05:45:37PM -0500, Konrad Rzeszutek Wilk wrote:
> > > (early) [    0.000000] memblock_find: [0x0, 0xfcdd000) size=8409088 align=4096 nid=1024
> > > (early) [    0.000000] memblock_find: [0x805000, 0xfcdd000) - adjusted
> > > (early) [    0.000000] memblock_find: cand [0x10567000, 0x100000000) -> (early) [0xfcdd000, 0xfcdd000) (early) - rejected
> > > (early) [    0.000000] memblock_find: cand [0x1e03000, 0x220a000) -> (early) [0x1e03000, 0x220a000) (early) - rejected
> > > (early) [    0.000000] memblock_find: cand [0x100000, 0x1000000) -> (early) [0x805000, 0x1000000) (early) - rejected
> > > (early) [    0.000000] memblock_find: cand [0x10000, 0x9b000) -> (early) [0x805000, 0x805000) (early) - rejected
> > > (early) [    0.000000] Kernel panic - not syncing: Cannot find space for the kernel page tables
> > 
> > So, it actually is a legitimate alloc failure.  It seems I've tried a
> > bit too hard at simplifying the allocator.  Does the following fix the
> > problem?

Any thoughts of when this fix will show up in Ingo's tree for 3.3-rc0?

Thanks!
> > 
> > Thanks.
> > 
> > diff --git a/mm/memblock.c b/mm/memblock.c
> > index 2f55f19..77b5f22 100644
> > --- a/mm/memblock.c
> > +++ b/mm/memblock.c
> > @@ -106,14 +106,17 @@ phys_addr_t __init_memblock memblock_find_in_range_node(phys_addr_t start,
> >  	if (end == MEMBLOCK_ALLOC_ACCESSIBLE)
> >  		end = memblock.current_limit;
> >  
> > -	/* adjust @start to avoid underflow and allocating the first page */
> > -	start = max3(start, size, (phys_addr_t)PAGE_SIZE);
> > +	/* avoid allocating the first page */
> > +	start = max_t(phys_addr_t, start, PAGE_SIZE);
> >  	end = max(start, end);
> >  
> >  	for_each_free_mem_range_reverse(i, nid, &this_start, &this_end, NULL) {
> >  		this_start = clamp(this_start, start, end);
> >  		this_end = clamp(this_end, start, end);
> >  
> > +		if (this_end < size)
> > +			continue;
> > +
> >  		cand = round_down(this_end - size, align);
> >  		if (cand >= this_start)
> >  			return cand;
> 
> With that patch it boots!
> 
> 
> (early) [    0.000000] Initializing cgroup subsys cpuset
> (early) [    0.000000] Initializing cgroup subsys cpu
> (early) [    0.000000] Linux version 3.2.0-05693-g2c81411 (konrad@phenom.dumpdata.com) (gcc version 4.4.4 20100503 (Red Hat 4.4.4-2) (GCC) ) #1 SMP PREEMPT Wed Jan 11 13:19:30 EST 2012
> (early) [    0.000000] Command line: console=hvc0 debug earlyprintk=xen
> (early) [    0.000000] ACPI in unprivileged domain disabled
> (early) [    0.000000] Released 0 pages of unused memory
> (early) [    0.000000] Set 0 page(s) to 1-1 mapping
> (early) [    0.000000] BIOS-provided physical RAM map:
> (early) [    0.000000]  Xen: 0000000000000000 - 00000000000a0000 (usable)
> (early) [    0.000000]  Xen: 00000000000a0000 - 0000000000100000 (reserved)
> (early) [    0.000000]  Xen: 0000000000100000 - 0000000200800000 (usable)
> (early) [    0.000000] bootconsole [xenboot0] enabled
> (early) [    0.000000] NX (Execute Disable) protection: active
> (early) [    0.000000] DMI not present or invalid.
> (early) [    0.000000] e820 update range: 0000000000000000 - 0000000000010000 (early) (usable)(early)  ==> (early) (reserved)(early) 
> (early) [    0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (early) (usable)(early) 
> (early) [    0.000000] No AGP bridge found
> (early) [    0.000000] last_pfn = 0x200800 max_arch_pfn = 0x400000000
> (early) [    0.000000] last_pfn = 0x100000 max_arch_pfn = 0x400000000
> (early) [    0.000000] initial memory mapped : 0 - 10006000
> (early) [    0.000000] Base memory trampoline at [ffff88000009b000] 9b000 size 20480
> (early) [    0.000000] init_memory_mapping: 0000000000000000-0000000100000000
> (early) [    0.000000]  0000000000 - 0100000000 page 4k
> (early) [    0.000000] kernel direct mapping tables up to 100000000 @ 7fb000-1000000
> (early) [    0.000000] xen: setting RW the range f74000 - 1000000
> (early) [    0.000000] init_memory_mapping: 0000000100000000-0000000200800000
> (early) [    0.000000]  0100000000 - 0200800000 page 4k
> (early) [    0.000000] kernel direct mapping tables up to 200800000 @ feff2000-100000000
> (early) [    0.000000] xen: setting RW the range ff7fb000 - 100000000
> (early) [    0.000000] RAMDISK: 02249000 - 10006000
> (early) [    0.000000] No NUMA configuration found
> (early) [    0.000000] Faking a node at 0000000000000000-0000000200800000
> (early) [    0.000000] Initmem setup node 0 0000000000000000-0000000200800000
> (early) [    0.000000]   NODE_DATA [00000000fffd9000 - 00000000ffffffff]
> (early) [    0.000000] Zone PFN ranges:
> (early) [    0.000000]   DMA      (early) 0x00000010 -> 0x00001000
> (early) [    0.000000]   DMA32    (early) 0x00001000 -> 0x00100000
> (early) [    0.000000]   Normal   (early) 0x00100000 -> 0x00200800
> (early) [    0.000000] Movable zone start PFN for each node
> (early) [    0.000000] Early memory PFN ranges
> (early) [    0.000000]     0: 0x00000010 -> 0x000000a0
> (early) [    0.000000]     0: 0x00000100 -> 0x00200800
> (early) [    0.000000] On node 0 totalpages: 2099088
> (early) [    0.000000]   DMA zone: 64 pages used for memmap
> (early) [    0.000000]   DMA zone: 1918 pages reserved
> (early) [    0.000000]   DMA zone: 2002 pages, LIFO batch:0
> (early) [    0.000000]   DMA32 zone: 16320 pages used for memmap
> (early) [    0.000000]   DMA32 zone: 1028160 pages, LIFO batch:31
> (early) [    0.000000]   Normal zone: 16416 pages used for memmap
> (early) [    0.000000]   Normal zone: 1034208 pages, LIFO batch:31
> (early) [    0.000000] SMP: Allowing 4 CPUs, 0 hotplug CPUs
> (early) [    0.000000] No local APIC present
> (early) [    0.000000] APIC: disable apic facility
> (early) [    0.000000] APIC: switched to apic NOOP
> (early) [    0.000000] nr_irqs_gsi: 16
> (early) [    0.000000] PM: Registered nosave memory: 00000000000a0000 - 0000000000100000
> (early) [    0.000000] PCI: Warning: Cannot find a gap in the 32bit address range
> (early) [    0.000000] PCI: Unassigned devices with 32bit resource registers may break!
> (early) [    0.000000] Allocating PCI resources starting at 200900000 (gap: 200900000:400000)
> (early) [    0.000000] Booting paravirtualized kernel on Xen
> (early) [    0.000000] Xen version: 4.1-120111 (preserve-AD)
> (early) [    0.000000] setup_percpu: NR_CPUS:4096 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
> (early) [    0.000000] PERCPU: Embedded 28 pages/cpu @ffff8800fff31000 s82752 r8192 d23744 u114688
> (early) [    0.000000] pcpu-alloc: s82752 r8192 d23744 u114688 alloc=28*4096(early) 
> (early) [    0.000000] pcpu-alloc: (early) [0] (early) 0 (early) [0] (early) 1 (early) [0] (early) 2 (early) [0] (early) 3 (early) 
> (early) [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 2064370
> (early) [    0.000000] Policy zone: Normal
> (early) [    0.000000] Kernel command line: console=hvc0 debug earlyprintk=xen
> (early) [    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
> (early) [    0.000000] Checking aperture...
> (early) [    0.000000] No AGP bridge found
> (early) [    0.000000] Calgary: detecting Calgary via BIOS EBDA area
> (early) [    0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing!
> (early) [    0.000000] Memory: 3727344k/8396800k available (6482k kernel code, 448k absent, 4669008k reserved, 4624k data, 1256k init)
> (early) [    0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
> (early) [    0.000000] Preemptible hierarchical RCU implementation.
> (early) [    0.000000] NR_IRQS:262400 nr_irqs:304 16
> (early) [    0.000000] kmemleak: Early log buffer exceeded, please increase DEBUG_KMEMLEAK_EARLY_LOG_SIZE
> (early) [    0.000000] kmemleak: Kernel memory leak detector disabled
> (early) [    0.000000] Console: colour dummy device 80x25
> (early) [    0.000000] console [tty0] enabled
> [    0.000000] console [hvc0] enabled, bootconsole disabled
> (early) [    0.000000] console [hvc0] enabled, bootconsole disabled
> [    0.000000] Xen: using vcpuop timer interface
> [    0.000000] installing Xen timer for CPU 0
> [    0.000000] Detected 3292.616 MHz processor.
> [    0.000999] Calibrating delay loop (skipped), value calculated using timer frequency.. 6585.23 BogoMIPS (lpj=3292616)
> [    0.000999] pid_max: default: 32768 minimum: 301
> [    0.000999] Security Framework initialized
> [    0.000999] SELinux:  Initializing.
> [    0.000999] SELinux:  Starting in permissive mode
> [    0.002170] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes)
> [    0.004675] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes)
> [    0.005213] Mount-cache hash table entries: 256
> [    0.005428] Initializing cgroup subsys cpuacct
> [    0.005436] Initializing cgroup subsys freezer
> [    0.005497] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
> [    0.005498] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
> [    0.005510] CPU: Physical Processor ID: 0
> [    0.005515] CPU: Processor Core ID: 0
> [    0.005576] SMP alternatives: switching to UP code
> [    0.005999] ftrace: allocating 23453 entries in 92 pages
> [    0.006057] cpu 0 spinlock event irq 17
> [    0.006112] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only.
> [    0.012038] NMI watchdog disabled (cpu0): hardware events not enabled
> [    0.018010] installing Xen timer for CPU 1
> [    0.018028] cpu 1 spinlock event irq 23
> [    0.018051] SMP alternatives: switching to SMP code
> [    0.019085] NMI watchdog disabled (cpu1): hardware events not enabled
> [    0.025009] installing Xen timer for CPU 2
> [    0.025027] cpu 2 spinlock event irq 29
> [    0.025121] NMI watchdog disabled (cpu2): hardware events not enabled
> [    0.031008] installing Xen timer for CPU 3
> [    0.031024] cpu 3 spinlock event irq 35
> [    0.031118] NMI watchdog disabled (cpu3): hardware events not enabled
> [    0.033006] Brought up 4 CPUs
> [    0.033666] kworker/u:0 used greatest stack depth: 5272 bytes left
> [    0.033666] Grant tables using version 2 layout.
> [    0.033666] Grant table initialized
> [    0.052849] RTC time: 165:165:165, date: 165/165/65
> [    0.053033] NET: Registered protocol family 16
> [    0.054556] PCI: setting up Xen PCI frontend stub
> [    0.054565] PCI: pci_cache_line_size set to 64 bytes
> [    0.065198] bio: create slab <bio-0> at 0
> [    0.065198] ACPI: Interpreter disabled.
> [    0.066004] xen/balloon: Initialising balloon driver.
> [    0.076187] xen-balloon: Initialising balloon driver.
> [    0.076187] vgaarb: loaded
> [    0.077064] usbcore: registered new interface driver usbfs
> [    0.077067] usbcore: registered new interface driver hub
> [    0.077107] usbcore: registered new device driver usb
> [    0.077107] PCI: System does not support PCI
> [    0.077107] PCI: System does not support PCI
> [    0.078054] NetLabel: Initializing
> [    0.078054] NetLabel:  domain hash size = 128
> [    0.078054] NetLabel:  protocols = UNLABELED CIPSOv4
> [    0.078054] NetLabel:  unlabeled traffic allowed by default
> [    0.078109] Switching to clocksource xen
> [    0.083574] pnp: PnP ACPI: disabled
> [    0.089832] PCI: max bus depth: 0 pci_try_num: 1
> [    0.089877] NET: Registered protocol family 2
> [    0.090665] IP route cache hash table entries: 262144 (order: 9, 2097152 bytes)
> [    0.093957] TCP established hash table entries: 524288 (order: 11, 8388608 bytes)
> [    0.095324] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
> [    0.095466] TCP: Hash tables configured (established 524288 bind 65536)
> [    0.095474] TCP reno registered
> [    0.095526] UDP hash table entries: 4096 (order: 5, 131072 bytes)
> [    0.095597] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes)
> [    0.095720] NET: Registered protocol family 1
> [    0.095957] RPC: Registered named UNIX socket transport module.
> [    0.095965] RPC: Registered udp transport module.
> [    0.095970] RPC: Registered tcp transport module.
> [    0.095976] RPC: Registered tcp NFSv4.1 backchannel transport module.
> [    0.095984] PCI: CLS 0 bytes, default 64
> [    0.096219] Trying to unpack rootfs image as initramfs...
> [    0.431565] Freeing initrd memory: 227060k freed
> [    0.475737] DMA-API: preallocated 32768 debug entries
> [    0.475961] DMA-API: debugging enabled by kernel config
> [    0.475969] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
> [    0.475977] Placing 64MB software IO TLB between ffff8800f2800000 - ffff8800f6800000
> [    0.475984] software IO TLB at phys 0xf2800000 - 0xf6800000
> [    0.476324] platform rtc_cmos: registered platform RTC device (no PNP device found)
> [    0.476472] Machine check injector initialized
> [    0.477452] microcode: CPU0 sig=0x206a7, pf=0x2, revision=0x17
> [    0.477466] microcode: CPU1 sig=0x206a7, pf=0x2, revision=0x17
> [    0.477486] microcode: CPU2 sig=0x206a7, pf=0x2, revision=0x17
> [    0.477503] microcode: CPU3 sig=0x206a7, pf=0x2, revision=0x17
> [    0.477586] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
> [    0.478032] audit: initializing netlink socket (disabled)
> [    0.478053] type=2000 audit(1326338469.110:1): initialized
> [    0.494006] HugeTLB registered 2 MB page size, pre-allocated 0 pages
> [    0.499995] VFS: Disk quotas dquot_6.5.2
> [    0.500208] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
> [    0.501045] NTFS driver 2.1.30 [Flags: R/W].
> [    0.501365] msgmni has been set to 7723
> [    0.501583] SELinux:  Registering netfilter hooks
> [    0.502294] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
> [    0.502303] io scheduler noop registered
> [    0.502309] io scheduler deadline registered
> [    0.502405] io scheduler cfq registered (default)
> [    0.502633] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
> [    0.542586] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
> [    0.573641] Non-volatile memory driver v1.3
> [    0.573649] Linux agpgart interface v0.103
> [    0.574083] [drm] Initialized drm 1.1.0 20060810
> [    0.577049] brd: module loaded
> [    0.578684] loop: module loaded
> [    0.579544] Fixed MDIO Bus: probed
> [    0.579551] tun: Universal TUN/TAP device driver, 1.6
> [    0.579556] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
> [    0.580300] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
> [    0.580308] ehci_hcd: block sizes: qh 112 qtd 96 itd 192 sitd 96
> [    0.580386] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
> [    0.580392] ohci_hcd: block sizes: ed 80 td 96
> [    0.580466] uhci_hcd: USB Universal Host Controller Interface driver
> [    0.580701] usbcore: registered new interface driver usblp
> [    0.580780] usbcore: registered new interface driver libusual
> [    0.581039] i8042: PNP: No PS/2 controller found. Probing ports directly.
> [    0.581865] i8042: No controller found
> [    0.581951] mousedev: PS/2 mouse device common for all mice
> [    0.622367] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0
> [    0.622469] rtc_cmos: probe of rtc_cmos failed with error -38
> [    0.622730] EFI Variables Facility v0.08 2004-May-17
> [    0.622805] zram: num_devices not specified. Using default: 1
> [    0.622812] zram: Creating 1 devices ...
> [    0.623448] Netfilter messages via NETLINK v0.30.
> [    0.623467] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
> [    0.623780] ctnetlink v0.93: registering with nfnetlink.
> [    0.625065] ip_tables: (C) 2000-2006 Netfilter Core Team
> [    0.625099] TCP cubic registered
> [    0.625105] Initializing XFRM netlink socket
> [    0.625468] NET: Registered protocol family 10
> [    0.627394] ip6_tables: (C) 2000-2006 Netfilter Core Team
> [    0.627485] IPv6 over IPv4 tunneling driver
> [    0.629062] NET: Registered protocol family 17
> [    0.629097] Registering the dns_resolver key type
> [    0.629362] PM: Hibernation image not present or could not be loaded.
> [    0.629386] registered taskstats version 1
> [    0.629448] XENBUS: Device with no driver: device/vif/0
> [    0.629454] XENBUS: Device with no driver: device/vfb/0
> [    0.629460] XENBUS: Device with no driver: device/vkbd/0
> [    0.629475]   Magic number: 1:252:3141
> [    0.630415] Freeing unused kernel memory: 1256k freed
> [    0.630622] Write protecting the kernel read-only data: 10240k
> [    0.636139] Freeing unused kernel memory: 1688k freed
> [    0.636508] Freeing unused kernel memory: 112k freed
> init started: BusyBox v1.14.3 (2012-01-11 13:22:11 EST)
> [    0.641820] consoletype used greatest stack depth: 5232 bytes left
> Mounting directories  [  OK  ]
> [    0.860513] modprobe used greatest stack depth: 5008 bytes left
> mount: mount point /sys/kernel/config does not exist
> [    0.865846] core_filesystem used greatest stack depth: 4880 bytes left
> [    0.874814] input: Xen Virtual Keyboard as /devices/virtual/input/input0
> [    0.875019] input: Xen Virtual Pointer as /devices/virtual/input/input1
> [    1.086237] Initialising Xen virtual ethernet driver.
> [    1.201417] udevd (1186): /proc/1186/oom_adj is deprecated, please use /proc/1186/oom_score_adj instead.
> udevd-work[1202]: error opening ATTR{/sys/devices/system/cpu/cpu0/online} for writing: No such file or directory
> 
> [    1.307448] ip used greatest stack depth: 3696 bytes left
> Waiting for devices [  OK  ]
> Waiting for fb [  OK  ]
> Starting..[/dev/fb0]
> /dev/fb0: len:0
> /dev/fb0: bits/pixel32
> (7fa51499a000): Writting .. [800:600]
> Done!
> FATAL: Module agpgart_intel not found.
> [    1.464693] Console: switching to colour frame buffer device 100x37
> [    1.478942] [drm] radeon kernel modesetting enabled.
> WARNING: Error inserting video (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/acpi/video.ko): No such device
> WARNING: Error inserting mxm_wmi (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/platform/x86/mxm-wmi.ko): No such device
> WARNING: Error inserting drm_kms_helper (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/gpu/drm/drm_kms_helper.ko): No such device
> WARNING: Error inserting ttm (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/gpu/drm/ttm/ttm.ko): No such device
> FATAL: Error inserting nouveau (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/gpu/drm/nouveau/nouveau.ko): No such device
> WARNING: Error inserting drm_kms_helper (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/gpu/drm/drm_kms_helper.ko): No such device
> FATAL: Error inserting i915 (/lib/modules/3.2.0-05693-g2c81411/kernel/drivers/gpu/drm/i915/i915.ko): No such device
> Starting..[/dev/fb0]
> /dev/fb0: len:0
> /dev/fb0: bits/pixel32
> (7fc2635c3000): Writting .. [800:600]
> Done!
> VGA: 0000:
> Waiting for network [  OK  ]
> Bringing up loopback interface:  [  OK  ]
> Bringing up interface eth0:  [    1.743980] device eth0 entered promiscuous mode
> [  OK  ]
> Bringing up interface switch:  
> Determining IP information for switch...[    1.790208] switch: port 1(eth0) entering forwarding state
> [    1.790244] switch: port 1(eth0) entering forwarding state
> [    1.790880] ip used greatest stack depth: 3376 bytes left
>  done.
> [  OK  ]
> Waiting for init.custom [  OK  ]
> 
> Starting SSHd ...
> 
>     SSH started [2314]
> 
> 
> Waiting for SSHd [  OK  ]
> WARNING: ssh currently running [2314] ignoring start request
> FATAL: Module dump_dma not found.
> ERROR: Module dump_dma does not exist in /proc/modules
> [    3.562254] SCSI subsystem initialized
> [    3.563911] Loading iSCSI transport class v2.0-870.
> [    3.566452] iscsi: registered transport (tcp)
> iscsistart: transport class version 2.0-870. iscsid version 2.0-872
> Could not get list of targets from firmware.
> Jan 12 03:21:12 g-pvops syslogd 1.5.0: restart.
> FATAL: Module evtchn not found.
> [    3.595640] Event-channel device installed.
> xencommons should be started first.
>            CPU0       CPU1       CPU2       CPU3       
>  16:       1330          0          0          0  xen-percpu-virq      timer0
>  17:          6          0          0          0  xen-percpu-ipi       spinlock0
>  18:       1872          0          0          0  xen-percpu-ipi       resched0
>  19:        129          0          0          0  xen-percpu-ipi       callfunc0
>  20:          0          0          0          0  xen-percpu-virq      debug0
>  21:         77          0          0          0  xen-percpu-ipi       callfuncsingle0
>  22:          0       1811          0          0  xen-percpu-virq      timer1
>  23:          0         14          0          0  xen-percpu-ipi       spinlock1
>  24:          0       2411          0          0  xen-percpu-ipi       resched1
>  25:          0        140          0          0  xen-percpu-ipi       callfunc1
>  26:          0          0          0          0  xen-percpu-virq      debug1
>  27:          0         78          0          0  xen-percpu-ipi       callfuncsingle1
>  28:          0          0       1204          0  xen-percpu-virq      timer2
>  29:          0          0          5          0  xen-percpu-ipi       spinlock2
>  30:          0          0       3713          0  xen-percpu-ipi       resched2
>  31:          0          0        148          0  xen-percpu-ipi       callfunc2
>  32:          0          0          0          0  xen-percpu-virq      debug2
>  33:          0          0        103          0  xen-percpu-ipi       callfuncsingle2
>  34:          0          0          0       1373  xen-percpu-virq      timer3
>  35:          0          0          0          5  xen-percpu-ipi       spinlock3
>  36:          0          0          0       2474  xen-percpu-ipi       resched3
>  37:          0          0          0        131  xen-percpu-ipi       callfunc3
>  38:          0          0          0          0  xen-percpu-virq      debug3
>  39:          0          0          0         88  xen-percpu-ipi       callfuncsingle3
>  40:        423          0          0          0   xen-dyn-event     xenbus
>  41:         81          0          0          0   xen-dyn-event     hvc_console
>  42:          0          0          0          0   xen-dyn-event     vkbd
>  43:         70          0          0          0   xen-dyn-event     vfb
>  44:         20          0          0          0   xen-dyn-event     eth0
> NMI:          0          0          0          0   Non-maskable interrupts
> LOC:          0          0          0          0   Local timer interrupts
> SPU:          0          0          0          0   Spurious interrupts
> PMI:          0          0          0          0   Performance monitoring interrupts
> IWI:          0          0          0          0   IRQ work interrupts
> RTR:          0          0          0          0   APIC ICR read retries
> RES:       1872       2411       3713       2474   Rescheduling interrupts
> CAL:        206        218        251        219   Function call interrupts
> TLB:          0          0          0          0   TLB shootdowns
> TRM:          0          0          0          0   Thermal event interrupts
> THR:          0          0          0          0   Threshold APIC interrupts
> MCE:          0          0          0          0   Machine check exceptions
> MCP:          0          0          0          0   Machine check polls
> ERR:          0
> MIS:          0
> 00000000-0000ffff : reserved
> 00010000-0009ffff : System RAM
> 000a0000-000fffff : reserved
>   000f0000-000fffff : System ROM
> 00100000-2007fffff : System RAM
>   01000000-016549db : Kernel code
>   016549dc-01ad89ff : Kernel data
>   01c1a000-01e2afff : Kernel bss
> Waiting for init.late [  OK  ]
> PING build.dumpdata.com (192.168.101.3) 56(84) bytes of data.
> 
> --- build.dumpdata.com ping statistics ---
> 1 packets transmitted, 1 received, 0% packet loss, time 0ms
> rtt min/avg/max/mdev = 0.329/0.329/0.329/0.000 ms
> NFS done
>  [0x0->0x100000] pfn
>  [0x0->0x100000] level entry
>  [0x100000->0x7cfffff] missing
>  [0x100000->0x7cfffff] level top
> libxl: error: libxl.c:56:libxl_ctx_init Is xenstore daemon running?
> failed to stat /var/run/xenstored.pid: No such file or directory
> cannot init xl context
> [    4.481012] device-mapper: ioctl: 4.22.0-ioctl (2011-10-19) initialised: dm-devel@redhat.com
> [    4.482152] device-mapper: multipath: version 1.3.0 loaded
> 192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
> 192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
> 192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
> 192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
> 192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
> 192.168.101.2:3260,1 iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb
> Logging in to [iface: default, target: iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb, portal: 192.168.101.2,3260]
> Jan 12 03:21:13 g-pvops iscsid: transport class version 2.0-870. iscsid version 2.0-872
> Jan 12 03:21:13 g-pvops iscsid: iSCSI daemon with pid=2387 started!
> [    4.747529] scsi0 : iSCSI Initiator over TCP/IP
> [    4.751948]  connection1:0: detected conn error (1020)
> iscsiadm: Could not login to [iface: default, target: iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb, portal: 192.168.101.2,3260].
> iscsiadm: initiator reported error (19 - encountered non-retryable iSCSI login failure)
> Jan 12 03:21:14 g-pvops iscsid: conn 0 login rejected: initiator failed authorization with target
> Jan 12 03:21:14 g-pvops iscsid: Connection1:0 to [target: iqn.2003-01.org.linux-iscsi.target:sn.726f464255f7b1dc47c8131b3471abeb, portal: 192.168.101.2,3260] through [iface: default] is shutdown.
> 
> poweroff
>   No matching physical volumes found
>   No volume groups found
> Jan 12 03:21:18 g-pvops init: starting pid 2466, tty '/dev/tty2': '/bin/sh'
> Jan 12 03:21:18 g-pvops init: starting pid 2469, tty '/dev/tty1': '/bin/sh'
> Jan 12 03:21:18 g-pvops init: starting pid 2470, tty '/dev/ttyS0': '/bin/sh'
> Jan 12 03:21:18 g-pvops init: starting pid 2471, tty '/dev/hvc0': '/bin/sh'
> 
>    ~~~~~~~~~~~~~~~~~~~~~~~~~~| DirectFB 1.4.9 |~~~~~~~~~~~~~~~~~~~~~~~~~~
>         (c) 2001-2010  The world wide DirectFB Open Source Community
>         (c) 2000-2004  Convergence (integrated media) GmbH
>       ----------------------------------------------------------------
> 
> (*) DirectFB/Core: Single Application Core. (2012-01-11 18:23) 
> sh-4.1# 
> sh-4.1# poweroff
> (*) Direct/Memcpy: Using Generic 64bit memcpy()
> Jan 12 03:21:18 g-pvops init: starting pid 2477, tty '': '/etc/init.d/halt'
> sh-4.1# Usage: /etc/init.d/halt {start}
> The system is going down NOW!
> Sent SIGTERM to all processes
> (!) [ 2465:    0.000] --> Caught signal 15 (sent by pid 1, uid 0) <--
> (!) [ 2465:    0.000] --> Caught signal 11 (at 0x38, invalid address) <--
> Sent SIGKILL to all processes
> Requesting system poweroff
> [   12.086915] System halted.
> Parsing config file /mnt/lab/tst018/pv.xm
> Daemon running with PID 3972

^ permalink raw reply	[flat|nested] 10+ messages in thread

* [PATCH 3.3-rc] memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node()
  2012-01-13 14:27         ` Konrad Rzeszutek Wilk
@ 2012-01-13 18:14           ` Tejun Heo
  2012-01-16  9:03             ` [tip:core/urgent] memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node () tip-bot for Tejun Heo
  0 siblings, 1 reply; 10+ messages in thread
From: Tejun Heo @ 2012-01-13 18:14 UTC (permalink / raw)
  To: Ingo Molnar, H. Peter Anvin
  Cc: linux-kernel, rjw, xen-devel, Konrad Rzeszutek Wilk,
	Benjamin Herrenschmidt

7bd0b0f0da "memblock: Reimplement memblock allocation using reverse
free area iterator" implemented simple top-down allocator using
reverse memblock iterator.  To avoid underflow in the allocator loop,
it simply raised the lower boundary to the requested size under the
assumption that requested size would be far smaller than available
memblocks.

This causes early page table allocation failure under certain
configurations.  Fix it by checking for underflow directly instead of
bumping up lower bound.

Signed-off-by: Tejun Heo <tj@kernel.org>
Reported-by: Konrad Rzeszutek Wilk <konrad.wilk@oracle.com>
LKML-Reference: <20120110202838.GA10402@phenom.dumpdata.com>
---
Sorry, I wrote the patch description and everything but forgot to
actually send it out. :)

Ingo, the new memblock allocator went too far with simplification and
caused unnecessary allocation failure.  The fix is fairly obvious and
simple.  Can you please route this patch?

Thanks.

 mm/memblock.c |    7 +++++--
 1 file changed, 5 insertions(+), 2 deletions(-)

diff --git a/mm/memblock.c b/mm/memblock.c
index 2f55f19..77b5f22 100644
--- a/mm/memblock.c
+++ b/mm/memblock.c
@@ -106,14 +106,17 @@ phys_addr_t __init_memblock memblock_find_in_range_node(phys_addr_t start,
 	if (end == MEMBLOCK_ALLOC_ACCESSIBLE)
 		end = memblock.current_limit;
 
-	/* adjust @start to avoid underflow and allocating the first page */
-	start = max3(start, size, (phys_addr_t)PAGE_SIZE);
+	/* avoid allocating the first page */
+	start = max_t(phys_addr_t, start, PAGE_SIZE);
 	end = max(start, end);
 
 	for_each_free_mem_range_reverse(i, nid, &this_start, &this_end, NULL) {
 		this_start = clamp(this_start, start, end);
 		this_end = clamp(this_end, start, end);
 
+		if (this_end < size)
+			continue;
+
 		cand = round_down(this_end - size, align);
 		if (cand >= this_start)
 			return cand;

^ permalink raw reply related	[flat|nested] 10+ messages in thread

* [tip:core/urgent] memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node ()
  2012-01-13 18:14           ` [PATCH 3.3-rc] memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node() Tejun Heo
@ 2012-01-16  9:03             ` tip-bot for Tejun Heo
  2012-01-24 14:51               ` Konrad Rzeszutek Wilk
  0 siblings, 1 reply; 10+ messages in thread
From: tip-bot for Tejun Heo @ 2012-01-16  9:03 UTC (permalink / raw)
  To: linux-tip-commits
  Cc: linux-kernel, hpa, mingo, konrad.wilk, torvalds, benh, akpm, tj,
	tglx, mingo

Commit-ID:  5d53cb27d849c899136c048ec84c940ac449494b
Gitweb:     http://git.kernel.org/tip/5d53cb27d849c899136c048ec84c940ac449494b
Author:     Tejun Heo <tj@kernel.org>
AuthorDate: Fri, 13 Jan 2012 10:14:12 -0800
Committer:  Ingo Molnar <mingo@elte.hu>
CommitDate: Mon, 16 Jan 2012 08:38:06 +0100

memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node()

7bd0b0f0da ("memblock: Reimplement memblock allocation using
reverse free area iterator") implemented a simple top-down
allocator using a reverse memblock iterator.  To avoid underflow
in the allocator loop, it simply raised the lower boundary to
the requested size under the assumption that requested size
would be far smaller than available memblocks.

This causes early page table allocation failure under certain
configurations in Xen.  Fix it by checking for underflow directly
instead of bumping up lower bound.

Signed-off-by: Tejun Heo <tj@kernel.org>
Reported-by: Konrad Rzeszutek Wilk <konrad.wilk@oracle.com>
Cc: rjw@sisk.pl
Cc: xen-devel@lists.xensource.com
Cc: Benjamin Herrenschmidt <benh@kernel.crashing.org>
Cc: Andrew Morton <akpm@linux-foundation.org>
Cc: Linus Torvalds <torvalds@linux-foundation.org>
Link: http://lkml.kernel.org/r/20120113181412.GA11112@google.com
Signed-off-by: Ingo Molnar <mingo@elte.hu>
---
 mm/memblock.c |    7 +++++--
 1 files changed, 5 insertions(+), 2 deletions(-)

diff --git a/mm/memblock.c b/mm/memblock.c
index 2f55f19..77b5f22 100644
--- a/mm/memblock.c
+++ b/mm/memblock.c
@@ -106,14 +106,17 @@ phys_addr_t __init_memblock memblock_find_in_range_node(phys_addr_t start,
 	if (end == MEMBLOCK_ALLOC_ACCESSIBLE)
 		end = memblock.current_limit;
 
-	/* adjust @start to avoid underflow and allocating the first page */
-	start = max3(start, size, (phys_addr_t)PAGE_SIZE);
+	/* avoid allocating the first page */
+	start = max_t(phys_addr_t, start, PAGE_SIZE);
 	end = max(start, end);
 
 	for_each_free_mem_range_reverse(i, nid, &this_start, &this_end, NULL) {
 		this_start = clamp(this_start, start, end);
 		this_end = clamp(this_end, start, end);
 
+		if (this_end < size)
+			continue;
+
 		cand = round_down(this_end - size, align);
 		if (cand >= this_start)
 			return cand;

^ permalink raw reply related	[flat|nested] 10+ messages in thread

* Re: [tip:core/urgent] memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node ()
  2012-01-16  9:03             ` [tip:core/urgent] memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node () tip-bot for Tejun Heo
@ 2012-01-24 14:51               ` Konrad Rzeszutek Wilk
  2012-01-25  3:58                 ` Konrad Rzeszutek Wilk
  0 siblings, 1 reply; 10+ messages in thread
From: Konrad Rzeszutek Wilk @ 2012-01-24 14:51 UTC (permalink / raw)
  To: mingo, hpa, linux-kernel, benh, akpm, torvalds, tj, tglx, mingo
  Cc: linux-tip-commits

On Mon, Jan 16, 2012 at 01:03:50AM -0800, tip-bot for Tejun Heo wrote:
> Commit-ID:  5d53cb27d849c899136c048ec84c940ac449494b
> Gitweb:     http://git.kernel.org/tip/5d53cb27d849c899136c048ec84c940ac449494b
> Author:     Tejun Heo <tj@kernel.org>
> AuthorDate: Fri, 13 Jan 2012 10:14:12 -0800
> Committer:  Ingo Molnar <mingo@elte.hu>
> CommitDate: Mon, 16 Jan 2012 08:38:06 +0100
> 
> memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node()
> 
> 7bd0b0f0da ("memblock: Reimplement memblock allocation using
> reverse free area iterator") implemented a simple top-down
> allocator using a reverse memblock iterator.  To avoid underflow
> in the allocator loop, it simply raised the lower boundary to
> the requested size under the assumption that requested size
> would be far smaller than available memblocks.
> 
> This causes early page table allocation failure under certain
> configurations in Xen.  Fix it by checking for underflow directly
> instead of bumping up lower bound.

Well, it fixes my 4GB problem, but when I try to boot a 2GB (1GB of that
is for balloon usage) guest (i386 only) I get a new failure:

> more /mnt/lab/bootstrap-i386/test.xm 
extra="console=hvc0 debug earlyprintk=xen memblock=debug"
kernel="/mnt/lab/bootstrap-i386/vmlinuz"
ramdisk="/mnt/lab/bootstrap-i386/initramfs.cpio.gz"
memory=1024
maxmem=2048
vcpus=2
name="bootstrap-i386"
on_crash="preserve"
vif = [ 'mac=00:0F:4B:00:00:68, bridge=switch' ]
vfb = [ 'vnc=1, vnclisten=0.0.0.0,vncunused=1']
disk=['phy:/dev/vg_guests/bootstrap-i386,xvda,w']

So this is v3.3-rc1 + this patch by itself. Any thoughts of what else
might be amiss?

Using config file "/mnt/lab/bootstrap-i386/test.xm".
Started domain bootstrap-i386 (id=2)
(early) [    0.000000] Reserving virtual address space above 0xf5800000
(early) [    0.000000] Initializing cgroup subsys cpuset
(early) [    0.000000] Initializing cgroup subsys cpu
(early) [    0.000000] Linux version 3.3.0-rc1-00065-gc9d7799 (konrad@build.dumpdata.com) (gcc version 4.4.4 20100503 (Red Hat 4.4.4-2) (GCC) ) #1 SMP PREEMPT Tue Jan 24 04:34:24 EST 2012
(early) [    0.000000] ACPI in unprivileged domain disabled
(early) [    0.000000] Released 0 pages of unused memory
(early) [    0.000000] Set 0 page(s) to 1-1 mapping
(early) [    0.000000] BIOS-provided physical RAM map:
(early) [    0.000000]  Xen: 0000000000000000 - 00000000000a0000 (usable)
(early) [    0.000000]  Xen: 00000000000a0000 - 0000000000100000 (reserved)
(early) [    0.000000]  Xen: 0000000000100000 - 0000000080800000 (usable)
(early) [    0.000000] bootconsole [xenboot0] enabled
(early) [    0.000000] NX (Execute Disable) protection: active
(early) [    0.000000] DMI not present or invalid.
(early) [    0.000000] e820 update range: 0000000000000000 - 0000000000010000 (early) (usable)(early)  ==> (early) (reserved)(early) 
(early) [    0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (early) (usable)(early) 
(early) [    0.000000] last_pfn = 0x80800 max_arch_pfn = 0x1000000
(early) [    0.000000] memblock_reserve: [0x0000000185a000-0x0000000186b000] setup_arch+0x67a/0xc9e
(early) [    0.000000] MEMBLOCK configuration:
(early) [    0.000000]  memory size = 0x80790000 reserved size = 0x59593000
(early) [    0.000000]  memory.cnt  = 0x2
(early) [    0.000000]  memory[0x0]	[0x00000000010000-0x0000000009ffff], 0x90000 bytes
(early) [    0.000000]  memory[0x1]	[0x00000000100000-0x000000807fffff], 0x80700000 bytes
(early) [    0.000000]  reserved.cnt  = 0x3
(early) [    0.000000]  reserved[0x0]	[0x00000001000000-0x0000000186afff], 0x86b000 bytes
(early) [    0.000000]  reserved[0x1]	[0x00000001ab4000-0x00000019fdbfff], 0x18528000 bytes
(early) [    0.000000]  reserved[0x2]	[0x00000040000000-0x000000807fffff], 0x40800000 bytes
(early) [    0.000000] initial memory mapped : 0 - 1abff000
(early) [    0.000000] memblock_reserve: [0x0000000009c000-0x000000000a0000] setup_trampolines+0x81/0xba
(early) [    0.000000] Base memory trampoline at [c009c000] 9c000 size 16384
(early) [    0.000000] init_memory_mapping: 0000000000000000-000000002d3fe000
(early) [    0.000000]  0000000000 - 002d3fe000 page 4k
(early) [    0.000000] kernel direct mapping tables up to 2d3fe000 @ 1aa92000-1abff000
(early) [    0.000000] memblock_reserve: [0x0000001aa92000-0x0000001ab26000] native_pagetable_reserve+0x22/0x28
(early) [    0.000000] xen: setting RW the range 1ab26000 - 1abff000
(early) [    0.000000]    memblock_free: [0x00000001ab4000-0x00000019e05000] setup_arch+0x7d5/0xc9e
(early) [    0.000000] initrd too large to handle, disabling initrd
(early) [    0.000000] 1332MB HIGHMEM available.
(early) [    0.000000] 723MB LOWMEM available.
(early) [    0.000000]   mapped low ram: 0 - 2d3fe000
(early) [    0.000000]   low ram: 0 - 2d3fe000
(early) [    0.000000] memblock_reserve: [0x0000002d3fd000-0x0000002d3fe000] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] Zone PFN ranges:
(early) [    0.000000]   DMA      (early) 0x00000010 -> 0x00001000
(early) [    0.000000]   Normal   (early) 0x00001000 -> 0x0002d3fe
(early) [    0.000000]   HighMem  (early) 0x0002d3fe -> 0x00080800
(early) [    0.000000] Movable zone start PFN for each node
(early) [    0.000000] Early memory PFN ranges
(early) [    0.000000]     0: 0x00000010 -> 0x000000a0
(early) [    0.000000]     0: 0x00000100 -> 0x00080800
(early) [    0.000000] On node 0 totalpages: 526224
(early) [    0.000000] memblock_reserve: [0x0000002c3ed000-0x0000002d3fd000] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] free_area_init_node: node 0, pgdat c170c540, node_mem_map ec3ed200
(early) [    0.000000]   DMA zone: 32 pages used for memmap
(early) [    0.000000]   DMA zone: 0 pages reserved
(early) [    0.000000]   DMA zone: 3952 pages, LIFO batch:0
(early) [    0.000000] memblock_reserve: [0x0000002c3ecfc0-0x0000002c3ecfc4] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3ecf00-0x0000002c3ecfc0] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000]   Normal zone: 1416 pages used for memmap
(early) [    0.000000]   Normal zone: 179830 pages, LIFO batch:31
(early) [    0.000000] memblock_reserve: [0x0000002c3ece40-0x0000002c3ecec8] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e9e40-0x0000002c3ece40] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000]   HighMem zone: 2665 pages used for memmap
(early) [    0.000000]   HighMem zone: 338329 pages, LIFO batch:31
(early) [    0.000000] memblock_reserve: [0x0000002c3e9d40-0x0000002c3e9e3c] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e3d40-0x0000002c3e9d40] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] Using APIC driver default
(early) [    0.000000] SMP: Allowing 2 CPUs, 0 hotplug CPUs
(early) [    0.000000] Local APIC disabled by BIOS -- you can enable it with "lapic"
(early) [    0.000000] APIC: disable apic facility
(early) [    0.000000] APIC: switched to apic NOOP
(early) [    0.000000] nr_irqs_gsi: 16
(early) [    0.000000] memblock_reserve: [0x0000002c3e3c80-0x0000002c3e3d10] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e3c40-0x0000002c3e3c80] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e3c00-0x0000002c3e3c40] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e3bc0-0x0000002c3e3c00] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e3b80-0x0000002c3e3b90] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] PM: Registered nosave memory: 00000000000a0000 - 0000000000100000
(early) [    0.000000] Allocating PCI resources starting at 80800000 (gap: 80800000:7f800000)
(early) [    0.000000] Booting paravirtualized kernel on Xen
(early) [    0.000000] Xen version: 4.1-120124 (preserve-AD)
(early) [    0.000000] memblock_reserve: [0x0000002c3e3b40-0x0000002c3e3b72] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e3b00-0x0000002c3e3b32] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:2 nr_node_ids:1
(early) [    0.000000] memblock_reserve: [0x0000002c3e2b00-0x0000002c3e3b00] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e1b00-0x0000002c3e2b00] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3c5000-0x0000002c3e1000] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000]    memblock_free: [0x0000002c3d3000-0x0000002c3d3000] free_bootmem+0x2b/0x31
(early) [    0.000000]    memblock_free: [0x0000002c3e1000-0x0000002c3e1000] free_bootmem+0x2b/0x31
(early) [    0.000000] PERCPU: Embedded 14 pages/cpu @ec3c5000 s33600 r0 d23744 u57344
(early) [    0.000000] memblock_reserve: [0x0000002c3e1ac0-0x0000002c3e1ac4] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e1a80-0x0000002c3e1a84] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e1a40-0x0000002c3e1a48] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e1a00-0x0000002c3e1a08] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] pcpu-alloc: s33600 r0 d23744 u57344 alloc=14*4096(early) 
(early) [    0.000000] pcpu-alloc: (early) [0] (early) 0 (early) [0] (early) 1 (early) 
(early) [    0.000000] memblock_reserve: [0x0000002c3e1980-0x0000002c3e19f8] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] memblock_reserve: [0x0000002c3e1940-0x0000002c3e196c] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000]    memblock_free: [0x0000002c3e2b00-0x0000002c3e3b00] free_bootmem+0x2b/0x31
(early) [    0.000000]    memblock_free: [0x0000002c3e1b00-0x0000002c3e2b00] free_bootmem+0x2b/0x31
(early) [    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 522111
(early) [    0.000000] Kernel command line: console=hvc0 debug earlyprintk=xen memblock=debug
(early) [    0.000000] memblock_reserve: [0x0000002c3c1000-0x0000002c3c5000] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
(early) [    0.000000] memblock_reserve: [0x0000002c341000-0x0000002c3c1000] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
(early) [    0.000000] memblock_reserve: [0x0000002c301000-0x0000002c341000] __alloc_memory_core_early+0x8c/0xaa
(early) [    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
(early) [    0.000000] Initializing CPU#0
(early) [    0.000000] Initializing HighMem for node 0 (0002d3fe:00080800)
(early) [    0.000000] Memory: 1019624k/2105344k available (5450k kernel code, 28504k reserved, 1812k data, 508k init, 307208k highmem)
(early) [    0.000000] virtual kernel memory layout:
(early) [    0.000000]     fixmap  : 0xf5715000 - 0xf57ff000   ( 936 kB)
(early) [    0.000000]     pkmap   : 0xf5400000 - 0xf5600000   (2048 kB)
(early) [    0.000000]     vmalloc : 0xedbfe000 - 0xf53fe000   ( 120 MB)
(early) [    0.000000]     lowmem  : 0xc0000000 - 0xed3fe000   ( 723 MB)
(early) [    0.000000]       .init : 0xc1718000 - 0xc1797000   ( 508 kB)
(early) [    0.000000]       .data : 0xc1552935 - 0xc1717a40   (1812 kB)
(early) [    0.000000]       .text : 0xc1000000 - 0xc1552935   (5450 kB)
(early) [    0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
(early) [    0.000000] Preemptible hierarchical RCU implementation.
(early) [    0.000000] NR_IRQS:2304 nr_irqs:288 16
(early) [    0.000000] CPU 0 irqstacks, hard=ebc10000 soft=ebc12000
(early) [    0.000000] Console: colour dummy device 80x25
(early) [    0.000000] console [tty0] enabled
[    0.000000] console [hvc0] enabled, bootconsole disabled
(early) [    0.000000] console [hvc0] enabled, bootconsole disabled
[    0.000000] Xen: using vcpuop timer interface
[    0.000000] installing Xen timer for CPU 0
[    0.000000] Detected 3292.620 MHz processor.
[    0.000999] Calibrating delay loop (skipped), value calculated using timer frequency.. 6585.24 BogoMIPS (lpj=3292620)
[    0.000999] pid_max: default: 32768 minimum: 301
[    0.000999] Security Framework initialized
[    0.000999] SELinux:  Initializing.
[    0.000999] SELinux:  Starting in permissive mode
[    0.000999] Mount-cache hash table entries: 512
[    0.001120] Initializing cgroup subsys cpuacct
[    0.001130] Initializing cgroup subsys freezer
[    0.001263] CPU: Physical Processor ID: 0
[    0.001272] CPU: Processor Core ID: 0
[    0.001280] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.001281] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
[    0.001519] SMP alternatives: switching to UP code
[    0.002138] cpu 0 spinlock event irq 17
[    0.002298] Performance Events: unsupported p6 CPU model 42 no PMU driver, software events only.
[    0.008072] NMI watchdog disabled (cpu0): hardware events not enabled
[    0.014031] CPU 1 irqstacks, hard=ebc8a000 soft=ebc8c000
[    0.014041] installing Xen timer for CPU 1
[    0.014116] cpu 1 spinlock event irq 23
[    0.014145] SMP alternatives: switching to SMP code
[    0.000999] Initializing CPU#1
[    0.000999] Disabled fast string operations
[    0.015199] NMI watchdog disabled (cpu1): hardware events not enabled
[    0.017015] Brought up 2 CPUs
[    0.019112] kworker/u:0 used greatest stack depth: 6968 bytes left
[    0.019157] Grant tables using version 2 layout.
[    0.019157] Grant table initialized
[    0.038273] RTC time: 165:165:165, date: 165/165/65
[    0.038580] NET: Registered protocol family 16
[    0.041681] PCI: setting up Xen PCI frontend stub
[    0.041691] PCI: pci_cache_line_size set to 64 bytes
[    0.043149] kworker/u:0 used greatest stack depth: 6928 bytes left
[    0.046216] kworker/u:0 used greatest stack depth: 6784 bytes left
[    0.067350] kworker/u:0 used greatest stack depth: 6620 bytes left
[    0.086154] bio: create slab <bio-0> at 0
[    0.087533] ACPI: Interpreter disabled.
[    0.087647] xen/balloon: Initialising balloon driver.
[    0.092191] xen-balloon: Initialising balloon driver.
[    0.093348] vgaarb: loaded
[    0.095177] usbcore: registered new interface driver usbfs
[    0.095274] usbcore: registered new interface driver hub
[    0.096014] usbcore: registered new device driver usb
[    0.097187] PCI: System does not support PCI
[    0.097187] PCI: System does not support PCI
[    0.098239] NetLabel: Initializing
[    0.098239] NetLabel:  domain hash size = 128
[    0.098239] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.098274] NetLabel:  unlabeled traffic allowed by default
[    0.099464] Switching to clocksource xen
[    0.130294] pnp: PnP ACPI: disabled
[    0.156747] PCI: max bus depth: 0 pci_try_num: 1
[    0.156899] NET: Registered protocol family 2
[    0.157124] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.157918] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.158302] TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
[    0.158391] TCP: Hash tables configured (established 131072 bind 65536)
[    0.158402] TCP reno registered
[    0.158414] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.158429] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.158763] NET: Registered protocol family 1
[    0.159684] RPC: Registered named UNIX socket transport module.
[    0.159696] RPC: Registered udp transport module.
[    0.159704] RPC: Registered tcp transport module.
[    0.159712] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.159723] PCI: CLS 0 bytes, default 64
[    0.249509] DMA-API: preallocated 32768 debug entries
[    0.249524] DMA-API: debugging enabled by kernel config
[    0.250058] platform rtc_cmos: registered platform RTC device (no PNP device found)
[    0.250596] Machine check injector initialized
[    0.252469] microcode: no support for this CPU vendor
[    0.254141] audit: initializing netlink socket (disabled)
[    0.254196] type=2000 audit(1327444216.488:1): initialized
[    0.267418] highmem bounce pool size: 64 pages
[    0.267441] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.291085] kworker/u:0 used greatest stack depth: 6588 bytes left
[    0.293529] VFS: Disk quotas dquot_6.5.2
[    0.294081] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.297116] NTFS driver 2.1.30 [Flags: R/W].
[    0.298412] msgmni has been set to 1391
[    0.299046] SELinux:  Registering netfilter hooks
[    0.301611] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    0.301625] io scheduler noop registered
[    0.301633] io scheduler deadline registered
[    0.301663] io scheduler cfq registered (default)
[    0.302462] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.400113] kworker/u:1 used greatest stack depth: 6496 bytes left
[    0.461905] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.484000] Non-volatile memory driver v1.3
[    0.484022] Linux agpgart interface v0.103
[    0.487010] [drm] Initialized drm 1.1.0 20060810
[    0.502366] brd: module loaded
[    0.510758] loop: module loaded
[    0.512577] Fixed MDIO Bus: probed
[    0.512587] tun: Universal TUN/TAP device driver, 1.6
[    0.512595] tun: (C) 1999-2004 Max Krasnyansky <maxk@qualcomm.com>
[    0.514744] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.514756] ehci_hcd: block sizes: qh 68 qtd 96 itd 160 sitd 96
[    0.515062] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.515073] ohci_hcd: block sizes: ed 64 td 64
[    0.515319] uhci_hcd: USB Universal Host Controller Interface driver
[    0.515892] usbcore: registered new interface driver usblp
[    0.516182] usbcore: registered new interface driver libusual
[    0.516986] i8042: PNP: No PS/2 controller found. Probing ports directly.
[    0.517812] i8042: No controller found
[    0.518502] mousedev: PS/2 mouse device common for all mice
[    0.560132] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0
[    0.560440] rtc_cmos: probe of rtc_cmos failed with error -38
[    0.561343] EFI Variables Facility v0.08 2004-May-17
[    0.562715] Netfilter messages via NETLINK v0.30.
[    0.562771] nf_conntrack version 0.5.0 (15931 buckets, 63724 max)
[    0.563857] ctnetlink v0.93: registering with nfnetlink.
[    0.565724] ip_tables: (C) 2000-2006 Netfilter Core Team
[    0.565930] TCP cubic registered
[    0.565938] Initializing XFRM netlink socket
[    0.567531] NET: Registered protocol family 10
[    0.570396] ip6_tables: (C) 2000-2006 Netfilter Core Team
[    0.570615] IPv6 over IPv4 tunneling driver
[    0.573725] NET: Registered protocol family 17
[    0.573841] Registering the dns_resolver key type
[    0.573932] Using IPI No-Shortcut mode
[    0.574835] PM: Hibernation image not present or could not be loaded.
[    0.574914] registered taskstats version 1
[    0.575042] kmemleak: Kernel memory leak detector initialized
[    0.575065] kmemleak: Automatic memory scanning thread started
[    0.575189] XENBUS: Device with no driver: device/vkbd/0
[    0.575199] XENBUS: Device with no driver: device/vfb/0
[    0.575208] XENBUS: Device with no driver: device/vbd/51712
[    0.575216] XENBUS: Device with no driver: device/vif/0
[    0.575225] XENBUS: Device with no driver: device/console/0
[    0.575248]   Magic number: 1:252:3141
[    0.576109] Root-NFS: no NFS server address
[    0.576118] VFS: Unable to mount root fs via NFS, trying floppy.
[    0.576215] VFS: Cannot open root device "(null)" or unknown-block(2,0)
[    0.576224] Please append a correct "root=" boot option; here are the available partitions:
[    0.576244] Kernel panic - not syncing: VFS: Unable to mount root fs on unknown-block(2,0)

^ permalink raw reply	[flat|nested] 10+ messages in thread

* Re: [tip:core/urgent] memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node ()
  2012-01-24 14:51               ` Konrad Rzeszutek Wilk
@ 2012-01-25  3:58                 ` Konrad Rzeszutek Wilk
  0 siblings, 0 replies; 10+ messages in thread
From: Konrad Rzeszutek Wilk @ 2012-01-25  3:58 UTC (permalink / raw)
  To: mingo, hpa, linux-kernel, benh, akpm, torvalds, tj, tglx, mingo
  Cc: linux-tip-commits

On Tue, Jan 24, 2012 at 09:51:32AM -0500, Konrad Rzeszutek Wilk wrote:
> On Mon, Jan 16, 2012 at 01:03:50AM -0800, tip-bot for Tejun Heo wrote:
> > Commit-ID:  5d53cb27d849c899136c048ec84c940ac449494b
> > Gitweb:     http://git.kernel.org/tip/5d53cb27d849c899136c048ec84c940ac449494b
> > Author:     Tejun Heo <tj@kernel.org>
> > AuthorDate: Fri, 13 Jan 2012 10:14:12 -0800
> > Committer:  Ingo Molnar <mingo@elte.hu>
> > CommitDate: Mon, 16 Jan 2012 08:38:06 +0100
> > 
> > memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node()
> > 
> > 7bd0b0f0da ("memblock: Reimplement memblock allocation using
> > reverse free area iterator") implemented a simple top-down
> > allocator using a reverse memblock iterator.  To avoid underflow
> > in the allocator loop, it simply raised the lower boundary to
> > the requested size under the assumption that requested size
> > would be far smaller than available memblocks.
> > 
> > This causes early page table allocation failure under certain
> > configurations in Xen.  Fix it by checking for underflow directly
> > instead of bumping up lower bound.
> 
> Well, it fixes my 4GB problem, but when I try to boot a 2GB (1GB of that
> is for balloon usage) guest (i386 only) I get a new failure:
> 
> > more /mnt/lab/bootstrap-i386/test.xm 
> extra="console=hvc0 debug earlyprintk=xen memblock=debug"
> kernel="/mnt/lab/bootstrap-i386/vmlinuz"
> ramdisk="/mnt/lab/bootstrap-i386/initramfs.cpio.gz"
> memory=1024
> maxmem=2048
> vcpus=2
> name="bootstrap-i386"
> on_crash="preserve"
> vif = [ 'mac=00:0F:4B:00:00:68, bridge=switch' ]
> vfb = [ 'vnc=1, vnclisten=0.0.0.0,vncunused=1']
> disk=['phy:/dev/vg_guests/bootstrap-i386,xvda,w']
> 
> So this is v3.3-rc1 + this patch by itself. Any thoughts of what else
> might be amiss?

My apologies, I had been building new kernels everyday and the /lib/modules
was getting filled with v3.3-rc1-000XX making the initramfs bigger and bigger
until well - it no longer could fit in memory. And I added this specific patch
in my testing queue which triggered another build and caused the initramfs to
grow just too big:

> (early) [    0.000000] initrd too large to handle, disabling initrd

while the previous initramfs were under the size constraint.

So, the patch is good, I've retested it and fixed my AutoMaticBuildThingy
to clean up /lib/modules before the built and sorry for the scare! 

Please apply this patch to 3.3-rc1 at your earliest convience! Thanks!

^ permalink raw reply	[flat|nested] 10+ messages in thread

end of thread, other threads:[~2012-01-25  4:01 UTC | newest]

Thread overview: 10+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2012-01-10 20:28 Bootup regression introduced by 7bd0b0f0da3b1ec11cbcc798eb0ef747a1184077 ("memblock: Reimplement memblock allocation using reverse free area iterato") in v3.3-rc0 Konrad Rzeszutek Wilk
2012-01-10 22:26 ` Tejun Heo
2012-01-10 22:45   ` Konrad Rzeszutek Wilk
2012-01-10 23:15     ` Tejun Heo
2012-01-11 20:04       ` Konrad Rzeszutek Wilk
2012-01-13 14:27         ` Konrad Rzeszutek Wilk
2012-01-13 18:14           ` [PATCH 3.3-rc] memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node() Tejun Heo
2012-01-16  9:03             ` [tip:core/urgent] memblock: Fix alloc failure due to dumb underflow protection in memblock_find_in_range_node () tip-bot for Tejun Heo
2012-01-24 14:51               ` Konrad Rzeszutek Wilk
2012-01-25  3:58                 ` Konrad Rzeszutek Wilk

This is an external index of several public inboxes,
see mirroring instructions on how to clone and mirror
all data and code used by this external index.