Re: [PATCH] mm: cma: print allocation failure reason and bitmap status

[Jaewon Kim <jaewon31.kim@samsung.com>]

Hello Michal Hocko and and Michal Nazarewichz

On 2016년 12월 29일 23:20, Michal Nazarewicz wrote:
> On Thu, Dec 29 2016, Michal Hocko wrote:
>> On Thu 29-12-16 11:28:02, Jaewon Kim wrote:
>>> There are many reasons of CMA allocation failure such as EBUSY, ENOMEM, EINTR.
>>> This patch prints the error value and bitmap status to know available pages
>>> regarding fragmentation.
>>>
>>> This is an ENOMEM example with this patch.
>>> [   11.616321]  [2:   Binder:711_1:  740] cma: cma_alloc: alloc failed, req-size: 256 pages, ret: -12
>>> [   11.616365]  [2:   Binder:711_1:  740] number of available pages: 4+7+7+8+38+166+127=>357 pages, total: 2048 pages
>> Could you be more specific why this part is useful?
The first line is useful to know why the allocation failed.
Actually CMA internally try all available regions because some regions can be failed because of EBUSY.
The second showing bitmap status is useful to know in detail on both ENONEM and EBUSY;
 ENOMEM:  not tried at all because of no available region
 EBUSY:  tried some region but all failed
>>> Signed-off-by: Jaewon Kim <jaewon31.kim@samsung.com>
>>> ---
>>>  mm/cma.c | 29 ++++++++++++++++++++++++++++-
>>>  1 file changed, 28 insertions(+), 1 deletion(-)
>>>
>>> diff --git a/mm/cma.c b/mm/cma.c
>>> index c960459..535aa39 100644
>>> --- a/mm/cma.c
>>> +++ b/mm/cma.c
>>> @@ -369,7 +369,7 @@ struct page *cma_alloc(struct cma *cma, size_t count, unsigned int align)
>>>  	unsigned long start = 0;
>>>  	unsigned long bitmap_maxno, bitmap_no, bitmap_count;
>>>  	struct page *page = NULL;
>>> -	int ret;
>>> +	int ret = -ENOMEM;
>>>  
>>>  	if (!cma || !cma->count)
>>>  		return NULL;
>>> @@ -427,6 +427,33 @@ struct page *cma_alloc(struct cma *cma, size_t count, unsigned int align)
>>>  	trace_cma_alloc(pfn, page, count, align);
>>>  
>>>  	pr_debug("%s(): returned %p\n", __func__, page);
>>> +
>>> +	if (ret != 0) {
>>> +		unsigned int nr, nr_total = 0;
>>> +		unsigned long next_set_bit;
>>> +
>>> +		pr_info("%s: alloc failed, req-size: %zu pages, ret: %d\n",
>>> +			__func__, count, ret);
>>> +		mutex_lock(&cma->lock);
>>> +		printk("number of available pages: ");
>>> +		start = 0;
>>> +		for (;;) {
>>> +			bitmap_no = find_next_zero_bit(cma->bitmap, cma->count, start);
>>> +			next_set_bit = find_next_bit(cma->bitmap, cma->count, bitmap_no);
>>> +			nr = next_set_bit - bitmap_no;
>>> +			if (bitmap_no >= cma->count)
>>> +				break;
> Put this just next to ‘bitmap_no = …’ line.  No need to call
> find_next_bit if we’re gonna break anyway.
thank you I fixed
>>> +			if (nr_total == 0)
>>> +				printk("%u", nr);
>>> +			else
>>> +				printk("+%u", nr);
> Perhaps also include location of the hole?  Something like:
>
> 		pr_cont("%s%u@%u", nr_total ? "+" : "", nr, bitmap_no);
Thank you I fixed with @%lu
>
>>> +			nr_total += nr;
>>> +			start = bitmap_no + nr;
>>> +		}
>>> +		printk("=>%u pages, total: %lu pages\n", nr_total, cma->count);
>>> +		mutex_unlock(&cma->lock);
>>> +	}
>>> +
> I wonder if this should be wrapped in
>
> #ifdef CMA_DEBUG
> …
> #endif
>
> On one hand it’s relatively expensive (even involving mutex locking) on
> the other it’s in allocation failure path.
bitmap status, I think, could be in side of CMA_DEBUG with the mutex
but the first error log, I hope, to be out of CMA_DEBUG.
>
>>>  	return page;
>>>  }
>>>  
>>> -- 
>>> 1.9.1
>>>
>>> --
>>> To unsubscribe, send a message with 'unsubscribe linux-mm' in
>>> the body to majordomo@kvack.org.  For more info on Linux MM,
>>> see: http://www.linux-mm.org/ .
>>> Don't email: <a href=mailto:"dont@kvack.org"> email@kvack.org </a>
>> -- 
>> Michal Hocko
>> SUSE Labs
This is fixed patch following your comment.
Please review again
If it is OK, let me know whether I need to resend this patch as a new mail thread.


>From 7577cc94da3af27907aa6eec590d2ef51e4b9d80 Mon Sep 17 00:00:00 2001
From: Jaewon Kim <jaewon31.kim@samsung.com>
Date: Thu, 29 Dec 2016 11:00:16 +0900
Subject: [PATCH] mm: cma: print allocation failure reason and bitmap status

There are many reasons of CMA allocation failure such as EBUSY, ENOMEM, EINTR.
But we did not know error reason so far. This patch prints the error value.

Additionally if CONFIG_CMA_DEBUG is enabled, this patch shows bitmap status to
know available pages. Actually CMA internally try all available regions because
some regions can be failed because of EBUSY. Bitmap status is useful to know in
detail on both ENONEM and EBUSY;
 ENOMEM: not tried at all because of no available region
         it could be too small total region or could be fragmentation issue
 EBUSY:  tried some region but all failed

This is an ENOMEM example with this patch.
[   13.250961]  [1:   Binder:715_1:  846] cma: cma_alloc: alloc failed, req-size: 256 pages, ret: -12
Avabile pages also will be shown if CONFIG_CMA_DEBUG is enabled
[   13.251052]  [1:   Binder:715_1:  846] cma: number of available pages: 4@572+7@585+7@601+8@632+38@730+166@1114+127@1921=>357 pages, total: 2048 pages

Signed-off-by: Jaewon Kim <jaewon31.kim@samsung.com>
---
 mm/cma.c | 29 ++++++++++++++++++++++++++++-
 1 file changed, 28 insertions(+), 1 deletion(-)

diff --git a/mm/cma.c b/mm/cma.c
index c960459..1bcd9db 100644
--- a/mm/cma.c
+++ b/mm/cma.c
@@ -369,7 +369,7 @@ struct page *cma_alloc(struct cma *cma, size_t count, unsigned int align)
     unsigned long start = 0;
     unsigned long bitmap_maxno, bitmap_no, bitmap_count;
     struct page *page = NULL;
-    int ret;
+    int ret = -ENOMEM;
 
     if (!cma || !cma->count)
         return NULL;
@@ -427,6 +427,33 @@ struct page *cma_alloc(struct cma *cma, size_t count, unsigned int align)
     trace_cma_alloc(pfn, page, count, align);
 
     pr_debug("%s(): returned %p\n", __func__, page);
+
+    if (ret != 0)
+        pr_info("%s: alloc failed, req-size: %zu pages, ret: %d\n",
+            __func__, count, ret);
+#ifdef CONFIG_CMA_DEBUG
+    if (ret != 0) {
+        unsigned int nr, nr_total = 0;
+        unsigned long next_set_bit;
+
+        mutex_lock(&cma->lock);
+        pr_info("number of available pages: ");
+        start = 0;
+        for (;;) {
+            bitmap_no = find_next_zero_bit(cma->bitmap, cma->count, start);
+            if (bitmap_no >= cma->count)
+                break;
+            next_set_bit = find_next_bit(cma->bitmap, cma->count, bitmap_no);
+            nr = next_set_bit - bitmap_no;
+            pr_cont("%s%u@%lu", nr_total ? "+" : "", nr, bitmap_no);
+            nr_total += nr;
+            start = bitmap_no + nr;
+        }
+        pr_cont("=>%u pages, total: %lu pages\n", nr_total, cma->count);
+        mutex_unlock(&cma->lock);
+    }
+#endif
+
     return page;
 }
 
-- 
1.9.1