* Re: [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh
2018-05-18 7:24 [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh Sandipan Das
@ 2018-05-18 10:21 ` Thomas-Mich Richter
2018-05-18 10:29 ` Sandipan Das
2018-05-18 19:21 ` Arnaldo Carvalho de Melo
2018-07-04 6:57 ` kernel test robot
2 siblings, 1 reply; 14+ messages in thread
From: Thomas-Mich Richter @ 2018-05-18 10:21 UTC (permalink / raw)
To: Sandipan Das, acme, jolsa
Cc: linux-kernel, ravi.bangoria, naveen.n.rao, Hendrik Brueckner
On 05/18/2018 09:24 AM, Sandipan Das wrote:
> This test currently fails because the regular expressions for
> matching the output of perf script do not consider the symbol
> offsets to be part of the output.
>
> The symbol offsets are seen because of the default behaviour
> introduced by commit 4140d2ea74b3 ("perf script: Show symbol
> offsets by default").
>
> Before applying this patch:
>
> # perf test -v "probe libc's inet_pton & backtrace it with ping"
>
> 62: probe libc's inet_pton & backtrace it with ping :
> --- start ---
> test child forked, pid 30389
> ping 30406 [002] 307144.280983: probe_libc:inet_pton: (7f4117adf220)
> 7f4117adf220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)
> FAIL: expected backtrace entry 1 ".*inet_pton[[:space:]]\(/usr/lib64/libc-2.25.so|inlined\)$" got "7f4117adf220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)"
> test child finished with -1
> ---- end ----
> probe libc's inet_pton & backtrace it with ping: FAILED!
>
> After applying this patch:
>
> # perf test -v "probe libc's inet_pton & backtrace it with ping"
>
> 62: probe libc's inet_pton & backtrace it with ping :
> --- start ---
> test child forked, pid 30539
> ping 30556 [003] 307254.313217: probe_libc:inet_pton: (7fe19ab10220)
> 7fe19ab10220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)
> 7fe19aad5ebd getaddrinfo+0x11d (/usr/lib64/libc-2.25.so)
> 56351e3c1c71 main+0x891 (/usr/bin/ping)
> test child finished with 0
> ---- end ----
> probe libc's inet_pton & backtrace it with ping: Ok
>
> Signed-off-by: Sandipan Das <sandipan@linux.vnet.ibm.com>
> ---
> tools/perf/tests/shell/record+probe_libc_inet_pton.sh | 12 ++++++------
> 1 file changed, 6 insertions(+), 6 deletions(-)
>
> diff --git a/tools/perf/tests/shell/record+probe_libc_inet_pton.sh b/tools/perf/tests/shell/record+probe_libc_inet_pton.sh
> index ee86473643be..650b208f700f 100755
> --- a/tools/perf/tests/shell/record+probe_libc_inet_pton.sh
> +++ b/tools/perf/tests/shell/record+probe_libc_inet_pton.sh
> @@ -16,18 +16,18 @@ nm -g $libc 2>/dev/null | fgrep -q inet_pton || exit 254
> trace_libc_inet_pton_backtrace() {
> idx=0
> expected[0]="ping[][0-9 \.:]+probe_libc:inet_pton: \([[:xdigit:]]+\)"
> - expected[1]=".*inet_pton[[:space:]]\($libc|inlined\)$"
> + expected[1]=".*inet_pton\+0x[[:xdigit:]]+[[:space:]]\($libc|inlined\)$"
> case "$(uname -m)" in
> s390x)
> eventattr='call-graph=dwarf,max-stack=4'
> - expected[2]="gaih_inet.*[[:space:]]\($libc|inlined\)$"
> - expected[3]="(__GI_)?getaddrinfo[[:space:]]\($libc|inlined\)$"
> - expected[4]="main[[:space:]]\(.*/bin/ping.*\)$"
> + expected[2]="gaih_inet.*\+0x[[:xdigit:]]+[[:space:]]\($libc|inlined\)$"
> + expected[3]="(__GI_)?getaddrinfo\+0x[[:xdigit:]]+[[:space:]]\($libc|inlined\)$"
> + expected[4]="main\+0x[[:xdigit:]]+[[:space:]]\(.*/bin/ping.*\)$"
> ;;
> *)
> eventattr='max-stack=3'
> - expected[2]="getaddrinfo[[:space:]]\($libc\)$"
> - expected[3]=".*\(.*/bin/ping.*\)$"
> + expected[2]="getaddrinfo\+0x[[:xdigit:]]+[[:space:]]\($libc\)$"
> + expected[3]=".*\+0x[[:xdigit:]]+[[:space:]]\(.*/bin/ping.*\)$"
> ;;
> esac
>
>
This patch fails on s390. I used 4.17.0rc5 + fedora 27 and I get this output:
[root@p23lp27 perf]# ./perf test 59
59: probe libc's inet_pton & backtrace it with ping : Ok
[root@p23lp27 linux]# cd ~/linux; patch -p1 < ../inet_pton1
(Stripping trailing CRs from patch; use --binary to disable.)
patching file tools/perf/tests/shell/record+probe_libc_inet_pton.sh
[root@p23lp27 linux]# cd -;
[root@p23lp27 perf]# ./perf test 59
59: probe libc's inet_pton & backtrace it with ping : FAILED!
[root@p23lp27 perf]#
Debugging revealed this line as cause of failure:
FAIL: expected backtrace entry 2 "gaih_inet.*\+0x[[:xdigit:]]+[[:space:]]\(/usr/lib64/libc-2.26.so|inlined\)$" got "fdcb1 gaih_inet.constprop.7 (/usr/lib64/libc-2.26.so)"
Here is the output of the trace file
[root@p23lp27 perf]# perf script
ping 87291 [001] 96936.231618: probe_libc:inet_pton: (3ff96342378)
142378 __inet_pton (inlined)
fdcb1 gaih_inet.constprop.7 (/usr/lib64/libc-2.26.so)
100189 __GI_getaddrinfo (inlined)
398d main (/usr/bin/ping)
2303d __libc_start_main (/usr/lib64/libc-2.26.so)
457b [unknown] (/usr/bin/ping)
[root@p23lp27 perf]#
Hope this helps
--
Thomas Richter, Dept 3303, IBM s390 Linux Development, Boeblingen, Germany
--
Vorsitzende des Aufsichtsrats: Martina Koederitz
Geschäftsführung: Dirk Wittkopp
Sitz der Gesellschaft: Böblingen / Registergericht: Amtsgericht Stuttgart, HRB 243294
^ permalink raw reply [flat|nested] 14+ messages in thread
* Re: [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh
2018-05-18 10:21 ` Thomas-Mich Richter
@ 2018-05-18 10:29 ` Sandipan Das
2018-05-18 11:09 ` Thomas-Mich Richter
0 siblings, 1 reply; 14+ messages in thread
From: Sandipan Das @ 2018-05-18 10:29 UTC (permalink / raw)
To: Thomas-Mich Richter, acme, jolsa
Cc: linux-kernel, ravi.bangoria, naveen.n.rao, Hendrik Brueckner
Hi Thomas,
On 05/18/2018 03:51 PM, Thomas-Mich Richter wrote:
[...]
>
> This patch fails on s390. I used 4.17.0rc5 + fedora 27 and I get this output:
>
>
> [root@p23lp27 perf]# ./perf test 59
> 59: probe libc's inet_pton & backtrace it with ping : Ok
> [root@p23lp27 linux]# cd ~/linux; patch -p1 < ../inet_pton1
> (Stripping trailing CRs from patch; use --binary to disable.)
> patching file tools/perf/tests/shell/record+probe_libc_inet_pton.sh
> [root@p23lp27 linux]# cd -;
> [root@p23lp27 perf]# ./perf test 59
> 59: probe libc's inet_pton & backtrace it with ping : FAILED!
> [root@p23lp27 perf]#
>
> Debugging revealed this line as cause of failure:
>
>
> FAIL: expected backtrace entry 2 "gaih_inet.*\+0x[[:xdigit:]]+[[:space:]]\(/usr/lib64/libc-2.26.so|inlined\)$" got "fdcb1 gaih_inet.constprop.7 (/usr/lib64/libc-2.26.so)"
>
> Here is the output of the trace file
>
> [root@p23lp27 perf]# perf script
> ping 87291 [001] 96936.231618: probe_libc:inet_pton: (3ff96342378)
> 142378 __inet_pton (inlined)
> fdcb1 gaih_inet.constprop.7 (/usr/lib64/libc-2.26.so)
> 100189 __GI_getaddrinfo (inlined)
> 398d main (/usr/bin/ping)
> 2303d __libc_start_main (/usr/lib64/libc-2.26.so)
> 457b [unknown] (/usr/bin/ping)
>
> [root@p23lp27 perf]#
>
> Hope this helps
>
Can you please apply these two patches as well and then re-test?
[1] https://lkml.org/lkml/2018/5/17/112
[2] https://lkml.org/lkml/2018/5/17/113
- Sandipan
^ permalink raw reply [flat|nested] 14+ messages in thread
* Re: [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh
2018-05-18 10:29 ` Sandipan Das
@ 2018-05-18 11:09 ` Thomas-Mich Richter
2018-05-18 14:14 ` Arnaldo Carvalho de Melo
0 siblings, 1 reply; 14+ messages in thread
From: Thomas-Mich Richter @ 2018-05-18 11:09 UTC (permalink / raw)
To: Sandipan Das, acme, jolsa
Cc: linux-kernel, ravi.bangoria, naveen.n.rao, Hendrik Brueckner
On 05/18/2018 12:29 PM, Sandipan Das wrote:
> Hi Thomas,
>
> On 05/18/2018 03:51 PM, Thomas-Mich Richter wrote:
> [...]
>>
>> This patch fails on s390. I used 4.17.0rc5 + fedora 27 and I get this output:
>>
>>
>> [root@p23lp27 perf]# ./perf test 59
>> 59: probe libc's inet_pton & backtrace it with ping : Ok
>> [root@p23lp27 linux]# cd ~/linux; patch -p1 < ../inet_pton1
>> (Stripping trailing CRs from patch; use --binary to disable.)
>> patching file tools/perf/tests/shell/record+probe_libc_inet_pton.sh
>> [root@p23lp27 linux]# cd -;
>> [root@p23lp27 perf]# ./perf test 59
>> 59: probe libc's inet_pton & backtrace it with ping : FAILED!
>> [root@p23lp27 perf]#
>>
>> Debugging revealed this line as cause of failure:
>>
>>
>> FAIL: expected backtrace entry 2 "gaih_inet.*\+0x[[:xdigit:]]+[[:space:]]\(/usr/lib64/libc-2.26.so|inlined\)$" got "fdcb1 gaih_inet.constprop.7 (/usr/lib64/libc-2.26.so)"
>>
>> Here is the output of the trace file
>>
>> [root@p23lp27 perf]# perf script
>> ping 87291 [001] 96936.231618: probe_libc:inet_pton: (3ff96342378)
>> 142378 __inet_pton (inlined)
>> fdcb1 gaih_inet.constprop.7 (/usr/lib64/libc-2.26.so)
>> 100189 __GI_getaddrinfo (inlined)
>> 398d main (/usr/bin/ping)
>> 2303d __libc_start_main (/usr/lib64/libc-2.26.so)
>> 457b [unknown] (/usr/bin/ping)
>>
>> [root@p23lp27 perf]#
>>
>> Hope this helps
>>
>
> Can you please apply these two patches as well and then re-test?
>
> [1] https://lkml.org/lkml/2018/5/17/112
> [2] https://lkml.org/lkml/2018/5/17/113
>
> - Sandipan
>
Ahhh, yes that helped. Must have missed it. Thanks for the pointer.
--
Thomas Richter, Dept 3303, IBM s390 Linux Development, Boeblingen, Germany
--
Vorsitzende des Aufsichtsrats: Martina Koederitz
Geschäftsführung: Dirk Wittkopp
Sitz der Gesellschaft: Böblingen / Registergericht: Amtsgericht Stuttgart, HRB 243294
^ permalink raw reply [flat|nested] 14+ messages in thread
* Re: [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh
2018-05-18 11:09 ` Thomas-Mich Richter
@ 2018-05-18 14:14 ` Arnaldo Carvalho de Melo
2018-05-18 18:24 ` Arnaldo Carvalho de Melo
2018-05-22 6:09 ` Thomas-Mich Richter
0 siblings, 2 replies; 14+ messages in thread
From: Arnaldo Carvalho de Melo @ 2018-05-18 14:14 UTC (permalink / raw)
To: Thomas-Mich Richter
Cc: Sandipan Das, jolsa, linux-kernel, ravi.bangoria, naveen.n.rao,
Hendrik Brueckner
Em Fri, May 18, 2018 at 01:09:48PM +0200, Thomas-Mich Richter escreveu:
> On 05/18/2018 12:29 PM, Sandipan Das wrote:
> > Can you please apply these two patches as well and then re-test?
> > [1] https://lkml.org/lkml/2018/5/17/112
> > [2] https://lkml.org/lkml/2018/5/17/113
> Ahhh, yes that helped. Must have missed it. Thanks for the pointer.
Cool, can I take that as a Tested-by: Thomas?
- Arnaldo
^ permalink raw reply [flat|nested] 14+ messages in thread
* Re: [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh
2018-05-18 14:14 ` Arnaldo Carvalho de Melo
@ 2018-05-18 18:24 ` Arnaldo Carvalho de Melo
2018-05-22 6:09 ` Thomas-Mich Richter
1 sibling, 0 replies; 14+ messages in thread
From: Arnaldo Carvalho de Melo @ 2018-05-18 18:24 UTC (permalink / raw)
To: Thomas-Mich Richter
Cc: Sandipan Das, jolsa, linux-kernel, ravi.bangoria, naveen.n.rao,
Hendrik Brueckner
Em Fri, May 18, 2018 at 11:14:45AM -0300, Arnaldo Carvalho de Melo escreveu:
> Em Fri, May 18, 2018 at 01:09:48PM +0200, Thomas-Mich Richter escreveu:
> > On 05/18/2018 12:29 PM, Sandipan Das wrote:
> > > Can you please apply these two patches as well and then re-test?
>
> > > [1] https://lkml.org/lkml/2018/5/17/112
> > > [2] https://lkml.org/lkml/2018/5/17/113
>
> > Ahhh, yes that helped. Must have missed it. Thanks for the pointer.
>
> Cool, can I take that as a Tested-by: Thomas?
Actually I'll fold this patch into the patch that made this test break,
so that we keep the tree bisectable wrt that specific 'perf test' entry.
- Arnaldo
^ permalink raw reply [flat|nested] 14+ messages in thread
* Re: [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh
2018-05-18 14:14 ` Arnaldo Carvalho de Melo
2018-05-18 18:24 ` Arnaldo Carvalho de Melo
@ 2018-05-22 6:09 ` Thomas-Mich Richter
1 sibling, 0 replies; 14+ messages in thread
From: Thomas-Mich Richter @ 2018-05-22 6:09 UTC (permalink / raw)
To: Arnaldo Carvalho de Melo
Cc: Sandipan Das, jolsa, linux-kernel, ravi.bangoria, naveen.n.rao,
Hendrik Brueckner
On 05/18/2018 04:14 PM, Arnaldo Carvalho de Melo wrote:
> Em Fri, May 18, 2018 at 01:09:48PM +0200, Thomas-Mich Richter escreveu:
>> On 05/18/2018 12:29 PM, Sandipan Das wrote:
>>> Can you please apply these two patches as well and then re-test?
>
>>> [1] https://lkml.org/lkml/2018/5/17/112
>>> [2] https://lkml.org/lkml/2018/5/17/113
>
>> Ahhh, yes that helped. Must have missed it. Thanks for the pointer.
>
> Cool, can I take that as a Tested-by: Thomas?
>
> - Arnaldo
>
Sure
--
Thomas Richter, Dept 3303, IBM s390 Linux Development, Boeblingen, Germany
--
Vorsitzende des Aufsichtsrats: Martina Koederitz
Geschäftsführung: Dirk Wittkopp
Sitz der Gesellschaft: Böblingen / Registergericht: Amtsgericht Stuttgart, HRB 243294
^ permalink raw reply [flat|nested] 14+ messages in thread
* Re: [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh
2018-05-18 7:24 [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh Sandipan Das
2018-05-18 10:21 ` Thomas-Mich Richter
@ 2018-05-18 19:21 ` Arnaldo Carvalho de Melo
2018-05-18 19:28 ` Sandipan Das
2018-05-18 19:29 ` Arnaldo Carvalho de Melo
2018-07-04 6:57 ` kernel test robot
2 siblings, 2 replies; 14+ messages in thread
From: Arnaldo Carvalho de Melo @ 2018-05-18 19:21 UTC (permalink / raw)
To: Sandipan Das; +Cc: jolsa, linux-kernel, ravi.bangoria, naveen.n.rao, tmricht
Em Fri, May 18, 2018 at 12:54:17PM +0530, Sandipan Das escreveu:
> This test currently fails because the regular expressions for
> matching the output of perf script do not consider the symbol
> offsets to be part of the output.
>
> The symbol offsets are seen because of the default behaviour
> introduced by commit 4140d2ea74b3 ("perf script: Show symbol
> offsets by default").
>
> Before applying this patch:
>
> # perf test -v "probe libc's inet_pton & backtrace it with ping"
>
> 62: probe libc's inet_pton & backtrace it with ping :
> --- start ---
> test child forked, pid 30389
> ping 30406 [002] 307144.280983: probe_libc:inet_pton: (7f4117adf220)
> 7f4117adf220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)
> FAIL: expected backtrace entry 1 ".*inet_pton[[:space:]]\(/usr/lib64/libc-2.25.so|inlined\)$" got "7f4117adf220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)"
> test child finished with -1
> ---- end ----
> probe libc's inet_pton & backtrace it with ping: FAILED!
>
> After applying this patch:
>
> # perf test -v "probe libc's inet_pton & backtrace it with ping"
>
> 62: probe libc's inet_pton & backtrace it with ping :
> --- start ---
> test child forked, pid 30539
> ping 30556 [003] 307254.313217: probe_libc:inet_pton: (7fe19ab10220)
> 7fe19ab10220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)
> 7fe19aad5ebd getaddrinfo+0x11d (/usr/lib64/libc-2.25.so)
> 56351e3c1c71 main+0x891 (/usr/bin/ping)
> test child finished with 0
> ---- end ----
> probe libc's inet_pton & backtrace it with ping: Ok
Here it went from failing with:
[root@seventh ~]# perf test -v pton
64: probe libc's inet_pton & backtrace it with ping :
--- start ---
test child forked, pid 22590
ping 22607 [001] 12782.425689: probe_libc:inet_pton: (7f8686da4e40)
7f8686da4e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
FAIL: expected backtrace entry 1 ".*inet_pton[[:space:]]\(/usr/lib64/libc-2.26.so|inlined\)$" got "7f8686da4e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)"
test child finished with -1
---- end ----
probe libc's inet_pton & backtrace it with ping: FAILED!
[root@seventh ~]#
To failing with:
[root@seventh ~]# perf test -v pton
64: probe libc's inet_pton & backtrace it with ping :
--- start ---
test child forked, pid 28954
ping 28971 [002] 14277.711200: probe_libc:inet_pton: (7fc9d66e3e40)
7fc9d66e3e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
7fc9d66b02b4 getaddrinfo+0x124 (/usr/lib64/libc-2.26.so)
56075cb98f40 [unknown] (/usr/bin/ping)
FAIL: expected backtrace entry 3 ".*\+0x[[:xdigit:]]+[[:space:]]\(.*/bin/ping.*\)$" got "56075cb98f40 [unknown] (/usr/bin/ping)"
test child finished with -1
---- end ----
probe libc's inet_pton & backtrace it with ping: FAILED!
[root@seventh ~]#
Trying to figure this out...
- Arnaldo
^ permalink raw reply [flat|nested] 14+ messages in thread
* Re: [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh
2018-05-18 19:21 ` Arnaldo Carvalho de Melo
@ 2018-05-18 19:28 ` Sandipan Das
2018-05-18 19:29 ` Arnaldo Carvalho de Melo
1 sibling, 0 replies; 14+ messages in thread
From: Sandipan Das @ 2018-05-18 19:28 UTC (permalink / raw)
To: Arnaldo Carvalho de Melo
Cc: jolsa, linux-kernel, ravi.bangoria, naveen.n.rao, tmricht
Hi Arnaldo,
On 05/19/2018 12:51 AM, Arnaldo Carvalho de Melo wrote:
> Em Fri, May 18, 2018 at 12:54:17PM +0530, Sandipan Das escreveu:
>> This test currently fails because the regular expressions for
>> matching the output of perf script do not consider the symbol
>> offsets to be part of the output.
>>
>> The symbol offsets are seen because of the default behaviour
>> introduced by commit 4140d2ea74b3 ("perf script: Show symbol
>> offsets by default").
>>
>> Before applying this patch:
>>
>> # perf test -v "probe libc's inet_pton & backtrace it with ping"
>>
>> 62: probe libc's inet_pton & backtrace it with ping :
>> --- start ---
>> test child forked, pid 30389
>> ping 30406 [002] 307144.280983: probe_libc:inet_pton: (7f4117adf220)
>> 7f4117adf220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)
>> FAIL: expected backtrace entry 1 ".*inet_pton[[:space:]]\(/usr/lib64/libc-2.25.so|inlined\)$" got "7f4117adf220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)"
>> test child finished with -1
>> ---- end ----
>> probe libc's inet_pton & backtrace it with ping: FAILED!
>>
>> After applying this patch:
>>
>> # perf test -v "probe libc's inet_pton & backtrace it with ping"
>>
>> 62: probe libc's inet_pton & backtrace it with ping :
>> --- start ---
>> test child forked, pid 30539
>> ping 30556 [003] 307254.313217: probe_libc:inet_pton: (7fe19ab10220)
>> 7fe19ab10220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)
>> 7fe19aad5ebd getaddrinfo+0x11d (/usr/lib64/libc-2.25.so)
>> 56351e3c1c71 main+0x891 (/usr/bin/ping)
>> test child finished with 0
>> ---- end ----
>> probe libc's inet_pton & backtrace it with ping: Ok
>
> Here it went from failing with:
>
> [root@seventh ~]# perf test -v pton
> 64: probe libc's inet_pton & backtrace it with ping :
> --- start ---
> test child forked, pid 22590
> ping 22607 [001] 12782.425689: probe_libc:inet_pton: (7f8686da4e40)
> 7f8686da4e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
> FAIL: expected backtrace entry 1 ".*inet_pton[[:space:]]\(/usr/lib64/libc-2.26.so|inlined\)$" got "7f8686da4e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)"
> test child finished with -1
> ---- end ----
> probe libc's inet_pton & backtrace it with ping: FAILED!
> [root@seventh ~]#
>
> To failing with:
>
> [root@seventh ~]# perf test -v pton
> 64: probe libc's inet_pton & backtrace it with ping :
> --- start ---
> test child forked, pid 28954
> ping 28971 [002] 14277.711200: probe_libc:inet_pton: (7fc9d66e3e40)
> 7fc9d66e3e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
> 7fc9d66b02b4 getaddrinfo+0x124 (/usr/lib64/libc-2.26.so)
> 56075cb98f40 [unknown] (/usr/bin/ping)
> FAIL: expected backtrace entry 3 ".*\+0x[[:xdigit:]]+[[:space:]]\(.*/bin/ping.*\)$" got "56075cb98f40 [unknown] (/usr/bin/ping)"
> test child finished with -1
> ---- end ----
> probe libc's inet_pton & backtrace it with ping: FAILED!
> [root@seventh ~]#
>
> Trying to figure this out...
>
Looks like perf failed to resolve the symbol's name for the last entry
in the callchain. I did not consider this case. So, if this happens, we
would be better off using the original regex for the last line:
expected[3]=".*\(.*/bin/ping.*\)$"
- Sandipan
^ permalink raw reply [flat|nested] 14+ messages in thread
* Re: [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh
2018-05-18 19:21 ` Arnaldo Carvalho de Melo
2018-05-18 19:28 ` Sandipan Das
@ 2018-05-18 19:29 ` Arnaldo Carvalho de Melo
2018-05-18 19:30 ` Arnaldo Carvalho de Melo
1 sibling, 1 reply; 14+ messages in thread
From: Arnaldo Carvalho de Melo @ 2018-05-18 19:29 UTC (permalink / raw)
To: Sandipan Das; +Cc: jolsa, linux-kernel, ravi.bangoria, naveen.n.rao, tmricht
Em Fri, May 18, 2018 at 04:21:02PM -0300, Arnaldo Carvalho de Melo escreveu:
> Em Fri, May 18, 2018 at 12:54:17PM +0530, Sandipan Das escreveu:
> > This test currently fails because the regular expressions for
> > matching the output of perf script do not consider the symbol
> > offsets to be part of the output.
> >
> > The symbol offsets are seen because of the default behaviour
> > introduced by commit 4140d2ea74b3 ("perf script: Show symbol
> > offsets by default").
> >
> > Before applying this patch:
> >
> > # perf test -v "probe libc's inet_pton & backtrace it with ping"
> >
> > 62: probe libc's inet_pton & backtrace it with ping :
> > --- start ---
> > test child forked, pid 30389
> > ping 30406 [002] 307144.280983: probe_libc:inet_pton: (7f4117adf220)
> > 7f4117adf220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)
> > FAIL: expected backtrace entry 1 ".*inet_pton[[:space:]]\(/usr/lib64/libc-2.25.so|inlined\)$" got "7f4117adf220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)"
> > test child finished with -1
> > ---- end ----
> > probe libc's inet_pton & backtrace it with ping: FAILED!
> >
> > After applying this patch:
> >
> > # perf test -v "probe libc's inet_pton & backtrace it with ping"
> >
> > 62: probe libc's inet_pton & backtrace it with ping :
> > --- start ---
> > test child forked, pid 30539
> > ping 30556 [003] 307254.313217: probe_libc:inet_pton: (7fe19ab10220)
> > 7fe19ab10220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)
> > 7fe19aad5ebd getaddrinfo+0x11d (/usr/lib64/libc-2.25.so)
> > 56351e3c1c71 main+0x891 (/usr/bin/ping)
> > test child finished with 0
> > ---- end ----
> > probe libc's inet_pton & backtrace it with ping: Ok
>
> Here it went from failing with:
>
> [root@seventh ~]# perf test -v pton
> 64: probe libc's inet_pton & backtrace it with ping :
> --- start ---
> test child forked, pid 22590
> ping 22607 [001] 12782.425689: probe_libc:inet_pton: (7f8686da4e40)
> 7f8686da4e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
> FAIL: expected backtrace entry 1 ".*inet_pton[[:space:]]\(/usr/lib64/libc-2.26.so|inlined\)$" got "7f8686da4e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)"
> test child finished with -1
> ---- end ----
> probe libc's inet_pton & backtrace it with ping: FAILED!
> [root@seventh ~]#
>
> To failing with:
>
> [root@seventh ~]# perf test -v pton
> 64: probe libc's inet_pton & backtrace it with ping :
> --- start ---
> test child forked, pid 28954
> ping 28971 [002] 14277.711200: probe_libc:inet_pton: (7fc9d66e3e40)
> 7fc9d66e3e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
> 7fc9d66b02b4 getaddrinfo+0x124 (/usr/lib64/libc-2.26.so)
> 56075cb98f40 [unknown] (/usr/bin/ping)
> FAIL: expected backtrace entry 3 ".*\+0x[[:xdigit:]]+[[:space:]]\(.*/bin/ping.*\)$" got "56075cb98f40 [unknown] (/usr/bin/ping)"
> test child finished with -1
> ---- end ----
> probe libc's inet_pton & backtrace it with ping: FAILED!
> [root@seventh ~]#
>
> Trying to figure this out...
[root@seventh perf]# perf script
ping 29170 [001] 14644.810782: probe_libc:inet_pton: (7f0bac71be40)
7f0bac71be40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
7f0bac6e82b4 getaddrinfo+0x124 (/usr/lib64/libc-2.26.so)
5585e10adf40 [unknown] (/usr/bin/ping)
[root@seventh perf]# perf report --mmaps
# pid tid ppid comm
0 0 -1 |swapper
29170 29170 -1 |ping
5585e10ab000-5585e12dd000 r-xp 00000000 2771393 /usr/bin/ping
7f0babfcf000-7f0bac1ed000 r-xp 00000000 2762913 /usr/lib64/libpthread-2.26.so
7f0bac1ed000-7f0bac3f1000 r-xp 00000000 2753363 /usr/lib64/libdl-2.26.so
7f0bac3f1000-7f0bac608000 r-xp 00000000 2760078 /usr/lib64/libz.so.1.2.11
7f0bac608000-7f0bac9be000 r-xp 00000000 2753359 /usr/lib64/libc-2.26.so
7f0bac9be000-7f0bacd09000 r-xp 00000000 2762901 /usr/lib64/libm-2.26.so
7f0bacd09000-7f0bacf20000 r-xp 00000000 2762915 /usr/lib64/libresolv-2.26.so
7f0bacf20000-7f0bad3a8000 r-xp 00000000 2764213 /usr/lib64/libcrypto.so.1.1.0h
7f0bad3a8000-7f0bad5dc000 r-xp 00000000 2761070 /usr/lib64/libidn.so.11.6.18
7f0bad5dc000-7f0bad7e1000 r-xp 00000000 2760189 /usr/lib64/libcap.so.2.25
7f0bad7e1000-7f0bada08000 r-xp 00000000 2753353 /usr/lib64/ld-2.26.so
7ffe23d99000-7ffe23d9b000 r-xp 00000000 0 [vdso]
[root@seventh perf]#
So it is there, but I don't have debuginfo for ping, lets see if I add
it...
[root@seventh perf]# rpm -qf `which ping`
iputils-20161105-7.fc27.x86_64
[root@seventh perf]#
[root@seventh perf]# dnf debuginfo-install iputils
[root@seventh perf]# perf script
ping 29170 [001] 14644.810782: probe_libc:inet_pton: (7f0bac71be40)
7f0bac71be40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
7f0bac6e82b4 getaddrinfo+0x124 (/usr/lib64/libc-2.26.so)
5585e10adf40 main+0x880 (/usr/bin/ping)
[root@seventh perf]#
So we need to check if debuginfo is available, and if so, expect it to
resolve that entry to main, if not, expect it to _not_ resolve and have
[unknown] instead...
- Arnaldo
^ permalink raw reply [flat|nested] 14+ messages in thread
* Re: [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh
2018-05-18 19:29 ` Arnaldo Carvalho de Melo
@ 2018-05-18 19:30 ` Arnaldo Carvalho de Melo
0 siblings, 0 replies; 14+ messages in thread
From: Arnaldo Carvalho de Melo @ 2018-05-18 19:30 UTC (permalink / raw)
To: Sandipan Das; +Cc: jolsa, linux-kernel, ravi.bangoria, naveen.n.rao, tmricht
Em Fri, May 18, 2018 at 04:29:16PM -0300, Arnaldo Carvalho de Melo escreveu:
> Em Fri, May 18, 2018 at 04:21:02PM -0300, Arnaldo Carvalho de Melo escreveu:
> > Here it went from failing with:
> >
> > [root@seventh ~]# perf test -v pton
> > 64: probe libc's inet_pton & backtrace it with ping :
> > --- start ---
> > test child forked, pid 22590
> > ping 22607 [001] 12782.425689: probe_libc:inet_pton: (7f8686da4e40)
> > 7f8686da4e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
> > FAIL: expected backtrace entry 1 ".*inet_pton[[:space:]]\(/usr/lib64/libc-2.26.so|inlined\)$" got "7f8686da4e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)"
> > test child finished with -1
> > ---- end ----
> > probe libc's inet_pton & backtrace it with ping: FAILED!
> > [root@seventh ~]#
> >
> > To failing with:
> >
> > [root@seventh ~]# perf test -v pton
> > 64: probe libc's inet_pton & backtrace it with ping :
> > --- start ---
> > test child forked, pid 28954
> > ping 28971 [002] 14277.711200: probe_libc:inet_pton: (7fc9d66e3e40)
> > 7fc9d66e3e40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
> > 7fc9d66b02b4 getaddrinfo+0x124 (/usr/lib64/libc-2.26.so)
> > 56075cb98f40 [unknown] (/usr/bin/ping)
> > FAIL: expected backtrace entry 3 ".*\+0x[[:xdigit:]]+[[:space:]]\(.*/bin/ping.*\)$" got "56075cb98f40 [unknown] (/usr/bin/ping)"
> > test child finished with -1
> > ---- end ----
> > probe libc's inet_pton & backtrace it with ping: FAILED!
> > [root@seventh ~]#
> >
> > Trying to figure this out...
>
> [root@seventh perf]# perf script
> ping 29170 [001] 14644.810782: probe_libc:inet_pton: (7f0bac71be40)
> 7f0bac71be40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
> 7f0bac6e82b4 getaddrinfo+0x124 (/usr/lib64/libc-2.26.so)
> 5585e10adf40 [unknown] (/usr/bin/ping)
>
> [root@seventh perf]# perf report --mmaps
> # pid tid ppid comm
> 0 0 -1 |swapper
> 29170 29170 -1 |ping
> 5585e10ab000-5585e12dd000 r-xp 00000000 2771393 /usr/bin/ping
> 7f0babfcf000-7f0bac1ed000 r-xp 00000000 2762913 /usr/lib64/libpthread-2.26.so
> 7f0bac1ed000-7f0bac3f1000 r-xp 00000000 2753363 /usr/lib64/libdl-2.26.so
> 7f0bac3f1000-7f0bac608000 r-xp 00000000 2760078 /usr/lib64/libz.so.1.2.11
> 7f0bac608000-7f0bac9be000 r-xp 00000000 2753359 /usr/lib64/libc-2.26.so
> 7f0bac9be000-7f0bacd09000 r-xp 00000000 2762901 /usr/lib64/libm-2.26.so
> 7f0bacd09000-7f0bacf20000 r-xp 00000000 2762915 /usr/lib64/libresolv-2.26.so
> 7f0bacf20000-7f0bad3a8000 r-xp 00000000 2764213 /usr/lib64/libcrypto.so.1.1.0h
> 7f0bad3a8000-7f0bad5dc000 r-xp 00000000 2761070 /usr/lib64/libidn.so.11.6.18
> 7f0bad5dc000-7f0bad7e1000 r-xp 00000000 2760189 /usr/lib64/libcap.so.2.25
> 7f0bad7e1000-7f0bada08000 r-xp 00000000 2753353 /usr/lib64/ld-2.26.so
> 7ffe23d99000-7ffe23d9b000 r-xp 00000000 0 [vdso]
> [root@seventh perf]#
>
> So it is there, but I don't have debuginfo for ping, lets see if I add
> it...
>
> [root@seventh perf]# rpm -qf `which ping`
> iputils-20161105-7.fc27.x86_64
> [root@seventh perf]#
> [root@seventh perf]# dnf debuginfo-install iputils
>
> [root@seventh perf]# perf script
> ping 29170 [001] 14644.810782: probe_libc:inet_pton: (7f0bac71be40)
> 7f0bac71be40 __GI___inet_pton+0x0 (/usr/lib64/libc-2.26.so)
> 7f0bac6e82b4 getaddrinfo+0x124 (/usr/lib64/libc-2.26.so)
> 5585e10adf40 main+0x880 (/usr/bin/ping)
>
> [root@seventh perf]#
>
> So we need to check if debuginfo is available, and if so, expect it to
> resolve that entry to main, if not, expect it to _not_ resolve and have
> [unknown] instead...
But tis is not related to your recent patches, so I'm folding this last
one with the one adding the offsets and go on from there...
- Arnaldo
^ permalink raw reply [flat|nested] 14+ messages in thread
* [lkp-robot] [perf tests] 32a4debc7c: perf-sanity-tests.probe_libc's_inet_pton_&_backtrace_it_with_ping.fail
2018-05-18 7:24 [PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh Sandipan Das
@ 2018-07-04 6:57 ` kernel test robot
2018-05-18 19:21 ` Arnaldo Carvalho de Melo
2018-07-04 6:57 ` kernel test robot
2 siblings, 0 replies; 14+ messages in thread
From: kernel test robot @ 2018-07-04 6:57 UTC (permalink / raw)
To: Sandipan Das
Cc: acme, jolsa, linux-kernel, ravi.bangoria, naveen.n.rao, tmricht, lkp
[-- Attachment #1: Type: text/plain, Size: 954 bytes --]
FYI, we noticed the following commit (built with gcc-7):
commit: 32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f ("[PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh")
url: https://github.com/0day-ci/linux/commits/Sandipan-Das/perf-tests-Fix-regex-for-record-probe_libc_inet_pton-sh/20180521-130408
in testcase: perf-sanity-tests
with following parameters:
perf_compiler: gcc
on test machine: qemu-system-x86_64 -enable-kvm -cpu kvm64,+ssse3 -smp 2 -m 8G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 62
62: probe libc's inet_pton & backtrace it with ping : FAILED!
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
[-- Attachment #2: job-script --]
[-- Type: text/plain, Size: 4386 bytes --]
#!/bin/sh
export_top_env()
{
export suite='perf-sanity-tests'
export testcase='perf-sanity-tests'
export category='functional'
export job_origin='/lkp/lkp/src/allot/rand/vm-lkp-nex04-8G/perf-sanity-tests.yaml'
export testbox='vm-lkp-nex04-8G-2'
export tbox_group='vm-lkp-nex04-8G'
export kconfig='x86_64-fedora-25'
export compiler='gcc-7'
export queue='bisect'
export branch='linux-devel/devel-spot-201805211347'
export commit='32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f'
export submit_id='5b03dff30b9a932b3a767d71'
export job_file='/lkp/scheduled/vm-lkp-nex04-8G-2/perf-sanity-tests-gcc-debian-x86_64-2016-08-31.cgz-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f-20180522-11066-yve2a-1.yaml'
export id='1bdd8b616b538b8db798a8cdad4603b67ca4566f'
export model='qemu-system-x86_64 -enable-kvm -cpu kvm64,+ssse3'
export nr_vm=10
export nr_cpu=2
export memory='8G'
export hdd_partitions='/dev/vda'
export need_linux_perf=true
export need_kconfig='CONFIG_KVM_GUEST=y'
export ssh_base_port=23250
export rootfs='debian-x86_64-2016-08-31.cgz'
export enqueue_time='2018-05-22 17:16:35 +0800'
export _id='5b03e2ff0b9a932b3a767d72'
export _rt='/result/perf-sanity-tests/gcc/vm-lkp-nex04-8G/debian-x86_64-2016-08-31.cgz/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f'
export user='lkp'
export result_root='/result/perf-sanity-tests/gcc/vm-lkp-nex04-8G/debian-x86_64-2016-08-31.cgz/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/0'
export LKP_SERVER='inn'
export max_uptime=3600
export initrd='/osimage/debian/debian-x86_64-2016-08-31.cgz'
export bootloader_append='root=/dev/ram0
user=lkp
job=/lkp/scheduled/vm-lkp-nex04-8G-2/perf-sanity-tests-gcc-debian-x86_64-2016-08-31.cgz-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f-20180522-11066-yve2a-1.yaml
ARCH=x86_64
kconfig=x86_64-fedora-25
branch=linux-devel/devel-spot-201805211347
commit=32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f
BOOT_IMAGE=/pkg/linux/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/vmlinuz-4.17.0-rc5-00073-g32a4deb
max_uptime=3600
RESULT_ROOT=/result/perf-sanity-tests/gcc/vm-lkp-nex04-8G/debian-x86_64-2016-08-31.cgz/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/0
LKP_SERVER=inn
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
net.ifnames=0
printk.devkmsg=on
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
drbd.minor_count=8
systemd.log_level=err
ignore_loglevel
console=tty0
earlyprintk=ttyS0,115200
console=ttyS0,115200
vga=normal
rw'
export modules_initrd='/pkg/linux/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/modules.cgz'
export bm_initrd='/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig_2016-11-15.cgz,/osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2018-05-14.cgz,/osimage/deps/debian-x86_64-2016-08-31.cgz/rsync-rootfs_2016-11-15.cgz,/osimage/deps/debian-x86_64-2016-08-31.cgz/perf-sanity-tests_2017-12-29.cgz,/osimage/pkg/debian-x86_64-2016-08-31.cgz/perf-x86_64-771c577c23ba_2018-05-21.cgz'
export linux_perf_initrd='/pkg/linux/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/linux-perf.cgz'
export lkp_initrd='/lkp/lkp/lkp-x86_64.cgz'
export site='inn'
export LKP_CGI_PORT=80
export LKP_CIFS_PORT=139
export kernel='/pkg/linux/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/vmlinuz-4.17.0-rc5-00073-g32a4deb'
export dequeue_time='2018-05-22 17:33:08 +0800'
export job_initrd='/lkp/scheduled/vm-lkp-nex04-8G-2/perf-sanity-tests-gcc-debian-x86_64-2016-08-31.cgz-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f-20180522-11066-yve2a-1.cgz'
[ -n "$LKP_SRC" ] ||
export LKP_SRC=/lkp/${user:-lkp}/src
}
run_job()
{
echo $$ > $TMP/run-job.pid
. $LKP_SRC/lib/http.sh
. $LKP_SRC/lib/job.sh
. $LKP_SRC/lib/env.sh
export_top_env
run_monitor $LKP_SRC/monitors/wrapper kmsg
run_monitor $LKP_SRC/monitors/wrapper heartbeat
run_monitor $LKP_SRC/monitors/wrapper oom-killer
run_monitor $LKP_SRC/monitors/plain/watchdog
run_test perf_compiler='gcc' $LKP_SRC/tests/wrapper perf-sanity-tests
}
extract_stats()
{
$LKP_SRC/stats/wrapper perf-sanity-tests
$LKP_SRC/stats/wrapper kmsg
$LKP_SRC/stats/wrapper time perf-sanity-tests.time
$LKP_SRC/stats/wrapper time
$LKP_SRC/stats/wrapper dmesg
$LKP_SRC/stats/wrapper kmsg
$LKP_SRC/stats/wrapper stderr
$LKP_SRC/stats/wrapper last_state
}
"$@"
[-- Attachment #3: dmesg.xz --]
[-- Type: application/x-xz, Size: 20828 bytes --]
[-- Attachment #4: perf-sanity-tests --]
[-- Type: text/plain, Size: 25349 bytes --]
2018-05-22 17:39:10 make ARCH= -C /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf
make: Entering directory '/usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf'
BUILD: Doing 'make ^[[33m-j2^[[m' parallel build
HOSTCC fixdep.o
HOSTLD fixdep-in.o
LINK fixdep
Auto-detecting system features:
... dwarf: [ ^[[32mon^[[m ]
... dwarf_getlocations: [ ^[[32mon^[[m ]
... glibc: [ ^[[32mon^[[m ]
... gtk2: [ ^[[31mOFF^[[m ]
... libaudit: [ ^[[32mon^[[m ]
... libbfd: [ ^[[32mon^[[m ]
... libelf: [ ^[[32mon^[[m ]
... libnuma: [ ^[[32mon^[[m ]
... numa_num_possible_cpus: [ ^[[32mon^[[m ]
... libperl: [ ^[[31mOFF^[[m ]
... libpython: [ ^[[32mon^[[m ]
... libslang: [ ^[[31mOFF^[[m ]
... libcrypto: [ ^[[31mOFF^[[m ]
... libunwind: [ ^[[32mon^[[m ]
... libdw-dwarf-unwind: [ ^[[32mon^[[m ]
... zlib: [ ^[[32mon^[[m ]
... lzma: [ ^[[32mon^[[m ]
... get_cpuid: [ ^[[32mon^[[m ]
... bpf: [ ^[[32mon^[[m ]
GEN common-cmds.h
CC fd/array.o
LD fd/libapi-in.o
CC fs/fs.o
CC fs/tracing_path.o
CC event-parse.o
LD fs/libapi-in.o
CC cpu.o
CC debug.o
CC str_error_r.o
LD libapi-in.o
AR libapi.a
CC exec-cmd.o
CC help.o
CC event-plugin.o
CC pager.o
CC trace-seq.o
CC parse-options.o
CC parse-filter.o
CC parse-utils.o
CC kbuffer-parse.o
LD libtraceevent-in.o
LINK libtraceevent.a
CC libbpf.o
CC run-command.o
CC bpf.o
CC sigchain.o
CC nlattr.o
CC subcmd-config.o
LD libbpf-in.o
LINK libbpf.a
HOSTCC pmu-events/json.o
LD libsubcmd-in.o
AR libsubcmd.a
CC plugin_jbd2.o
HOSTCC pmu-events/jsmn.o
LD plugin_jbd2-in.o
CC plugin_hrtimer.o
HOSTCC pmu-events/jevents.o
LD plugin_hrtimer-in.o
CC plugin_kmem.o
LD plugin_kmem-in.o
CC plugin_kvm.o
HOSTLD pmu-events/jevents-in.o
GEN perf-archive
GEN perf-with-kcore
CC util/annotate.o
LD plugin_kvm-in.o
CC plugin_mac80211.o
LD plugin_mac80211-in.o
CC plugin_sched_switch.o
LD plugin_sched_switch-in.o
CC plugin_function.o
LD plugin_function-in.o
CC plugin_xen.o
LD plugin_xen-in.o
CC plugin_scsi.o
LD plugin_scsi-in.o
CC plugin_cfg80211.o
LD plugin_cfg80211-in.o
LINK plugin_jbd2.so
LINK plugin_hrtimer.so
LINK plugin_kmem.so
LINK plugin_kvm.so
LINK plugin_mac80211.so
LINK plugin_sched_switch.so
LINK plugin_function.so
LINK plugin_xen.so
LINK plugin_scsi.so
LINK plugin_cfg80211.so
CC builtin-bench.o
CC builtin-annotate.o
CC builtin-config.o
CC builtin-diff.o
CC util/block-range.o
CC util/build-id.o
CC builtin-evlist.o
CC builtin-ftrace.o
CC util/config.o
CC builtin-help.o
CC builtin-sched.o
CC util/ctype.o
CC util/db-export.o
CC util/env.o
CC util/event.o
CC util/evlist.o
CC builtin-buildid-list.o
CC builtin-buildid-cache.o
CC builtin-kallsyms.o
CC builtin-list.o
CC util/evsel.o
CC builtin-record.o
CC builtin-report.o
CC builtin-stat.o
CC util/evsel_fprintf.o
CC util/find_bit.o
CC util/kallsyms.o
CC util/levenshtein.o
CC util/llvm-utils.o
CC util/mmap.o
CC util/memswap.o
BISON util/parse-events-bison.c
CC builtin-timechart.o
CC util/perf_regs.o
CC util/path.o
CC util/print_binary.o
CC util/rbtree.o
CC util/libstring.o
CC util/bitmap.o
CC util/hweight.o
CC util/smt.o
CC builtin-top.o
CC util/quote.o
CC util/strbuf.o
CC util/string.o
CC util/strlist.o
CC util/strfilter.o
CC builtin-script.o
CC util/top.o
CC util/usage.o
CC util/dso.o
CC util/symbol.o
CC builtin-kmem.o
CC util/symbol_fprintf.o
CC util/color.o
CC builtin-lock.o
CC util/metricgroup.o
CC builtin-kvm.o
CC util/header.o
CC builtin-inject.o
CC builtin-mem.o
CC builtin-data.o
CC builtin-version.o
CC builtin-c2c.o
CC util/callchain.o
CC builtin-trace.o
CC util/values.o
CC util/debug.o
CC util/machine.o
CC builtin-probe.o
CC bench/sched-messaging.o
CC bench/sched-pipe.o
CC util/map.o
CC bench/mem-functions.o
CC bench/futex-hash.o
CC bench/futex-wake.o
CC util/pstack.o
CC bench/futex-wake-parallel.o
CC util/session.o
CC bench/futex-requeue.o
CC bench/futex-lock-pi.o
CC bench/mem-memcpy-x86-64-asm.o
CC bench/mem-memset-x86-64-asm.o
CC bench/numa.o
CC util/syscalltbl.o
CC util/ordered-events.o
CC util/namespaces.o
LD bench/perf-in.o
CC tests/builtin-test.o
CC util/comm.o
CC util/thread.o
CC tests/parse-events.o
CC util/thread_map.o
CC util/trace-event-parse.o
CC util/parse-events-bison.o
CC tests/dso-data.o
BISON util/pmu-bison.c
CC util/trace-event-read.o
CC tests/attr.o
CC util/trace-event-info.o
CC tests/vmlinux-kallsyms.o
CC tests/openat-syscall.o
CC util/trace-event-scripting.o
CC tests/openat-syscall-all-cpus.o
CC util/trace-event.o
CC tests/openat-syscall-tp-fields.o
CC util/svghelper.o
CC tests/mmap-basic.o
CC tests/perf-record.o
CC util/sort.o
CC tests/evsel-roundtrip-name.o
CC tests/evsel-tp-sched.o
CC tests/fdarray.o
CC tests/pmu.o
CC tests/hists_common.o
CC tests/hists_link.o
CC util/hist.o
CC tests/hists_filter.o
CC tests/hists_output.o
CC tests/hists_cumulate.o
CC tests/python-use.o
CC tests/bp_signal.o
CC util/util.o
CC tests/bp_signal_overflow.o
CC tests/bp_account.o
CC util/xyarray.o
CC util/cpumap.o
CC tests/task-exit.o
CC tests/sw-clock.o
CC tests/mmap-thread-lookup.o
CC util/cgroup.o
CC tests/thread-mg-share.o
CC tests/switch-tracking.o
CC util/target.o
CC util/rblist.o
CC util/intlist.o
CC tests/keep-tracking.o
CC util/vdso.o
CC tests/code-reading.o
CC util/counts.o
CC util/stat.o
CC tests/sample-parsing.o
CC util/stat-shadow.o
CC tests/parse-no-sample-id-all.o
CC tests/kmod-path.o
CC tests/thread-map.o
CC tests/llvm.o
CC util/record.o
CC tests/bpf.o
CC util/srcline.o
CC tests/topology.o
CC tests/mem.o
CC util/data.o
CC tests/cpumap.o
CC util/tsc.o
CC util/cloexec.o
CC tests/stat.o
CC util/call-path.o
CC tests/event_update.o
CC util/rwsem.o
CC util/thread-stack.o
CC tests/event-times.o
CC tests/expr.o
CC util/auxtrace.o
CC tests/backward-ring-buffer.o
CC tests/sdt.o
CC tests/is_printable_array.o
CC tests/bitmap.o
CC tests/perf-hooks.o
CC tests/clang.o
CC util/intel-pt-decoder/intel-pt-pkt-decoder.o
CC tests/unit_number__scnprintf.o
CC tests/mem2node.o
GEN util/intel-pt-decoder/inat-tables.c
CC util/intel-pt-decoder/intel-pt-log.o
CC tests/dwarf-unwind.o
CC util/intel-pt-decoder/intel-pt-decoder.o
CC tests/llvm-src-base.o
CC tests/llvm-src-kbuild.o
CC tests/llvm-src-prologue.o
CC tests/llvm-src-relocation.o
LD tests/perf-in.o
CC perf.o
LD perf-in.o
CC util/intel-pt-decoder/intel-pt-insn-decoder.o
LINK pmu-events/jevents
GEN libtraceevent-dynamic-list
GEN pmu-events/pmu-events.c
CC pmu-events/pmu-events.o
LD pmu-events/pmu-events-in.o
GEN python/perf.so
LD util/intel-pt-decoder/libperf-in.o
CC util/scripting-engines/trace-event-python.o
LD util/scripting-engines/libperf-in.o
CC util/intel-pt.o
CC util/intel-bts.o
CC util/arm-spe.o
CC util/arm-spe-pkt-decoder.o
CC util/parse-branch-options.o
CC util/dump-insn.o
CC util/parse-regs-options.o
CC util/term.o
CC util/help-unknown-cmd.o
CC util/mem-events.o
CC util/vsprintf.o
CC util/drv_configs.o
CC util/units.o
CC util/time-utils.o
BISON util/expr-bison.c
CC util/branch.o
CC util/mem2node.o
CC util/bpf-loader.o
CC util/bpf-prologue.o
CC util/symbol-elf.o
CC util/probe-file.o
CC arch/common.o
CC arch/x86/util/header.o
CC arch/x86/util/tsc.o
CC util/probe-event.o
CC arch/x86/util/pmu.o
CC arch/x86/util/kvm-stat.o
CC arch/x86/util/perf_regs.o
CC arch/x86/util/group.o
CC arch/x86/util/dwarf-regs.o
CC arch/x86/util/unwind-libunwind.o
CC arch/x86/util/auxtrace.o
CC arch/x86/util/intel-pt.o
CC arch/x86/util/intel-bts.o
CC util/probe-finder.o
LD arch/x86/util/libperf-in.o
CC arch/x86/tests/regs_load.o
CC arch/x86/tests/dwarf-unwind.o
CC arch/x86/tests/arch-tests.o
CC arch/x86/tests/rdpmc.o
CC arch/x86/tests/perf-time-to-tsc.o
CC arch/x86/tests/insn-x86.o
CC util/dwarf-aux.o
LD arch/x86/tests/libperf-in.o
LD arch/x86/libperf-in.o
LD arch/libperf-in.o
CC ui/setup.o
CC ui/helpline.o
CC ui/progress.o
CC ui/util.o
CC util/dwarf-regs.o
CC ui/hist.o
CC util/unwind-libunwind-local.o
CC util/unwind-libunwind.o
CC util/zlib.o
CC util/lzma.o
CC util/demangle-java.o
CC util/demangle-rust.o
CC util/jitdump.o
CC ui/stdio/hist.o
CC util/genelf.o
CC util/genelf_debug.o
LD ui/libperf-in.o
CC scripts/python/Perf-Trace-Util/Context.o
LD scripts/python/Perf-Trace-Util/libperf-in.o
LD scripts/libperf-in.o
CC trace/beauty/clone.o
CC trace/beauty/fcntl.o
CC util/perf-hooks.o
CC trace/beauty/flock.o
CC trace/beauty/ioctl.o
FLEX util/parse-events-flex.c
FLEX util/pmu-flex.c
CC util/pmu-bison.o
CC trace/beauty/kcmp.o
CC trace/beauty/pkey_alloc.o
CC trace/beauty/prctl.o
CC util/expr-bison.o
CC trace/beauty/statx.o
LD trace/beauty/libperf-in.o
CC util/parse-events.o
CC util/parse-events-flex.o
CC util/pmu.o
CC util/pmu-flex.o
LD util/libperf-in.o
LD libperf-in.o
AR libperf.a
LINK perf
make: Leaving directory '/usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf'
2018-05-22 17:40:51 cd /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf
2018-05-22 17:40:51 mkdir -p /pkg
2018-05-22 17:40:51 mkdir -p /kbuild/obj/lkp/x86_64-fedora-25
2018-05-22 17:40:51 cp /pkg/linux/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/vmlinux.xz /tmp
2018-05-22 17:40:53 unxz -k /tmp/vmlinux.xz
2018-05-22 17:41:00 cp /tmp/vmlinux /kbuild/obj/lkp/x86_64-fedora-25
ignored_by_lkp: BPF filter
ignored_by_lkp: LLVM search and compile
ignored_by_lkp: Add vfs_getname probe to get syscall args filenames
ignored_by_lkp: Use vfs_getname probe to get syscall args filenames
ignored_by_lkp: Check open filename arg using perf trace + vfs_getname
ignored_by_lkp: builtin clang support
2018-05-22 17:41:01 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 1
1: vmlinux symtab matches kallsyms : Ok
2018-05-22 17:41:02 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 2
2: Detect openat syscall event : Ok
2018-05-22 17:41:02 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 3
3: Detect openat syscall event on all cpus : Ok
2018-05-22 17:41:02 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 4
4: Read samples using the mmap interface : Ok
2018-05-22 17:41:02 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 5
5: Test data source output : Ok
2018-05-22 17:41:02 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 6
6: Parse event definition strings : FAILED!
2018-05-22 17:41:03 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 7
7: Simple expression parser : Ok
2018-05-22 17:41:03 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 8
8: PERF_RECORD_* events & perf_sample fields : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 9
9: Parse perf pmu format : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 10
10: DSO data read : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 11
11: DSO data cache : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 12
12: DSO data reopen : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 13
13: Roundtrip evsel->name : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 14
14: Parse sched tracepoints fields : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 15
15: syscalls:sys_enter_openat event fields : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 16
16: Setup struct perf_event_attr : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 17
17: Match and link multiple hists : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 18
18: 'import perf' in python : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 19
19: Breakpoint overflow signal handler : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 20
20: Breakpoint overflow sampling : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 21
21: Breakpoint accounting : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 22
22: Number of exit events of a simple workload : Skip
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 23
23: Software clock events period values : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 24
24: Object code reading : FAILED!
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 25
25: Sample parsing : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 26
26: Use a dummy software event to keep tracking : Skip
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 27
27: Parse with no sample_id_all bit set : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 28
28: Filter hist entries : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 29
29: Lookup mmap thread : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 30
30: Share thread mg : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 31
31: Sort output of hist entries : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 32
32: Cumulate child hist entries : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 33
33: Track with sched_switch : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 34
34: Filter fds with revents mask in a fdarray : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 35
35: Add fd to a fdarray, making it autogrow : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 36
36: kmod_path__parse : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 37
37: Thread map : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 39
39: Session topology : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 41
41: Synthesize thread map : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 42
42: Remove thread map : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 43
43: Synthesize cpu map : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 44
44: Synthesize stat config : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 45
45: Synthesize stat : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 46
46: Synthesize stat round : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 47
47: Synthesize attr update : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 48
48: Event times : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 49
49: Read backward ring buffer : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 50
50: Print cpu map : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 51
51: Probe SDT events : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 52
52: is_printable_array : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 53
53: Print bitmap : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 54
54: perf hooks : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 56
56: unit_number__scnprintf : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 57
57: mem2node : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 58
58: x86 rdpmc : FAILED!
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 59
59: Convert perf time to TSC : FAILED!
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 60
60: DWARF unwind : Ok
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 61
61: x86 instruction decoder - new instructions : Ok
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 62
62: probe libc's inet_pton & backtrace it with ping : FAILED!
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 66
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 67
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 68
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 69
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 70
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 71
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 72
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 73
^ permalink raw reply [flat|nested] 14+ messages in thread
* [lkp-robot] [perf tests] 32a4debc7c: perf-sanity-tests.probe_libc's_inet_pton_&_backtrace_it_with_ping.fail
@ 2018-07-04 6:57 ` kernel test robot
0 siblings, 0 replies; 14+ messages in thread
From: kernel test robot @ 2018-07-04 6:57 UTC (permalink / raw)
To: lkp
[-- Attachment #1: Type: text/plain, Size: 988 bytes --]
FYI, we noticed the following commit (built with gcc-7):
commit: 32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f ("[PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh")
url: https://github.com/0day-ci/linux/commits/Sandipan-Das/perf-tests-Fix-regex-for-record-probe_libc_inet_pton-sh/20180521-130408
in testcase: perf-sanity-tests
with following parameters:
perf_compiler: gcc
on test machine: qemu-system-x86_64 -enable-kvm -cpu kvm64,+ssse3 -smp 2 -m 8G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 62
62: probe libc's inet_pton & backtrace it with ping : FAILED!
To reproduce:
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Xiaolong
[-- Attachment #2: job-script.ksh --]
[-- Type: text/plain, Size: 4386 bytes --]
#!/bin/sh
export_top_env()
{
export suite='perf-sanity-tests'
export testcase='perf-sanity-tests'
export category='functional'
export job_origin='/lkp/lkp/src/allot/rand/vm-lkp-nex04-8G/perf-sanity-tests.yaml'
export testbox='vm-lkp-nex04-8G-2'
export tbox_group='vm-lkp-nex04-8G'
export kconfig='x86_64-fedora-25'
export compiler='gcc-7'
export queue='bisect'
export branch='linux-devel/devel-spot-201805211347'
export commit='32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f'
export submit_id='5b03dff30b9a932b3a767d71'
export job_file='/lkp/scheduled/vm-lkp-nex04-8G-2/perf-sanity-tests-gcc-debian-x86_64-2016-08-31.cgz-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f-20180522-11066-yve2a-1.yaml'
export id='1bdd8b616b538b8db798a8cdad4603b67ca4566f'
export model='qemu-system-x86_64 -enable-kvm -cpu kvm64,+ssse3'
export nr_vm=10
export nr_cpu=2
export memory='8G'
export hdd_partitions='/dev/vda'
export need_linux_perf=true
export need_kconfig='CONFIG_KVM_GUEST=y'
export ssh_base_port=23250
export rootfs='debian-x86_64-2016-08-31.cgz'
export enqueue_time='2018-05-22 17:16:35 +0800'
export _id='5b03e2ff0b9a932b3a767d72'
export _rt='/result/perf-sanity-tests/gcc/vm-lkp-nex04-8G/debian-x86_64-2016-08-31.cgz/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f'
export user='lkp'
export result_root='/result/perf-sanity-tests/gcc/vm-lkp-nex04-8G/debian-x86_64-2016-08-31.cgz/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/0'
export LKP_SERVER='inn'
export max_uptime=3600
export initrd='/osimage/debian/debian-x86_64-2016-08-31.cgz'
export bootloader_append='root=/dev/ram0
user=lkp
job=/lkp/scheduled/vm-lkp-nex04-8G-2/perf-sanity-tests-gcc-debian-x86_64-2016-08-31.cgz-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f-20180522-11066-yve2a-1.yaml
ARCH=x86_64
kconfig=x86_64-fedora-25
branch=linux-devel/devel-spot-201805211347
commit=32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f
BOOT_IMAGE=/pkg/linux/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/vmlinuz-4.17.0-rc5-00073-g32a4deb
max_uptime=3600
RESULT_ROOT=/result/perf-sanity-tests/gcc/vm-lkp-nex04-8G/debian-x86_64-2016-08-31.cgz/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/0
LKP_SERVER=inn
debug
apic=debug
sysrq_always_enabled
rcupdate.rcu_cpu_stall_timeout=100
net.ifnames=0
printk.devkmsg=on
panic=-1
softlockup_panic=1
nmi_watchdog=panic
oops=panic
load_ramdisk=2
prompt_ramdisk=0
drbd.minor_count=8
systemd.log_level=err
ignore_loglevel
console=tty0
earlyprintk=ttyS0,115200
console=ttyS0,115200
vga=normal
rw'
export modules_initrd='/pkg/linux/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/modules.cgz'
export bm_initrd='/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig_2016-11-15.cgz,/osimage/deps/debian-x86_64-2016-08-31.cgz/lkp_2018-05-14.cgz,/osimage/deps/debian-x86_64-2016-08-31.cgz/rsync-rootfs_2016-11-15.cgz,/osimage/deps/debian-x86_64-2016-08-31.cgz/perf-sanity-tests_2017-12-29.cgz,/osimage/pkg/debian-x86_64-2016-08-31.cgz/perf-x86_64-771c577c23ba_2018-05-21.cgz'
export linux_perf_initrd='/pkg/linux/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/linux-perf.cgz'
export lkp_initrd='/lkp/lkp/lkp-x86_64.cgz'
export site='inn'
export LKP_CGI_PORT=80
export LKP_CIFS_PORT=139
export kernel='/pkg/linux/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/vmlinuz-4.17.0-rc5-00073-g32a4deb'
export dequeue_time='2018-05-22 17:33:08 +0800'
export job_initrd='/lkp/scheduled/vm-lkp-nex04-8G-2/perf-sanity-tests-gcc-debian-x86_64-2016-08-31.cgz-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f-20180522-11066-yve2a-1.cgz'
[ -n "$LKP_SRC" ] ||
export LKP_SRC=/lkp/${user:-lkp}/src
}
run_job()
{
echo $$ > $TMP/run-job.pid
. $LKP_SRC/lib/http.sh
. $LKP_SRC/lib/job.sh
. $LKP_SRC/lib/env.sh
export_top_env
run_monitor $LKP_SRC/monitors/wrapper kmsg
run_monitor $LKP_SRC/monitors/wrapper heartbeat
run_monitor $LKP_SRC/monitors/wrapper oom-killer
run_monitor $LKP_SRC/monitors/plain/watchdog
run_test perf_compiler='gcc' $LKP_SRC/tests/wrapper perf-sanity-tests
}
extract_stats()
{
$LKP_SRC/stats/wrapper perf-sanity-tests
$LKP_SRC/stats/wrapper kmsg
$LKP_SRC/stats/wrapper time perf-sanity-tests.time
$LKP_SRC/stats/wrapper time
$LKP_SRC/stats/wrapper dmesg
$LKP_SRC/stats/wrapper kmsg
$LKP_SRC/stats/wrapper stderr
$LKP_SRC/stats/wrapper last_state
}
"$@"
[-- Attachment #3: dmesg.xz --]
[-- Type: application/x-xz, Size: 20828 bytes --]
[-- Attachment #4: perf-sanity-tests.ksh --]
[-- Type: text/plain, Size: 24830 bytes --]
2018-05-22 17:39:10 make ARCH= -C /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf
make: Entering directory '/usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf'
BUILD: Doing 'make ^[[33m-j2^[[m' parallel build
HOSTCC fixdep.o
HOSTLD fixdep-in.o
LINK fixdep
Auto-detecting system features:
... dwarf: [ ^[[32mon^[[m ]
... dwarf_getlocations: [ ^[[32mon^[[m ]
... glibc: [ ^[[32mon^[[m ]
... gtk2: [ ^[[31mOFF^[[m ]
... libaudit: [ ^[[32mon^[[m ]
... libbfd: [ ^[[32mon^[[m ]
... libelf: [ ^[[32mon^[[m ]
... libnuma: [ ^[[32mon^[[m ]
... numa_num_possible_cpus: [ ^[[32mon^[[m ]
... libperl: [ ^[[31mOFF^[[m ]
... libpython: [ ^[[32mon^[[m ]
... libslang: [ ^[[31mOFF^[[m ]
... libcrypto: [ ^[[31mOFF^[[m ]
... libunwind: [ ^[[32mon^[[m ]
... libdw-dwarf-unwind: [ ^[[32mon^[[m ]
... zlib: [ ^[[32mon^[[m ]
... lzma: [ ^[[32mon^[[m ]
... get_cpuid: [ ^[[32mon^[[m ]
... bpf: [ ^[[32mon^[[m ]
GEN common-cmds.h
CC fd/array.o
LD fd/libapi-in.o
CC fs/fs.o
CC fs/tracing_path.o
CC event-parse.o
LD fs/libapi-in.o
CC cpu.o
CC debug.o
CC str_error_r.o
LD libapi-in.o
AR libapi.a
CC exec-cmd.o
CC help.o
CC event-plugin.o
CC pager.o
CC trace-seq.o
CC parse-options.o
CC parse-filter.o
CC parse-utils.o
CC kbuffer-parse.o
LD libtraceevent-in.o
LINK libtraceevent.a
CC libbpf.o
CC run-command.o
CC bpf.o
CC sigchain.o
CC nlattr.o
CC subcmd-config.o
LD libbpf-in.o
LINK libbpf.a
HOSTCC pmu-events/json.o
LD libsubcmd-in.o
AR libsubcmd.a
CC plugin_jbd2.o
HOSTCC pmu-events/jsmn.o
LD plugin_jbd2-in.o
CC plugin_hrtimer.o
HOSTCC pmu-events/jevents.o
LD plugin_hrtimer-in.o
CC plugin_kmem.o
LD plugin_kmem-in.o
CC plugin_kvm.o
HOSTLD pmu-events/jevents-in.o
GEN perf-archive
GEN perf-with-kcore
CC util/annotate.o
LD plugin_kvm-in.o
CC plugin_mac80211.o
LD plugin_mac80211-in.o
CC plugin_sched_switch.o
LD plugin_sched_switch-in.o
CC plugin_function.o
LD plugin_function-in.o
CC plugin_xen.o
LD plugin_xen-in.o
CC plugin_scsi.o
LD plugin_scsi-in.o
CC plugin_cfg80211.o
LD plugin_cfg80211-in.o
LINK plugin_jbd2.so
LINK plugin_hrtimer.so
LINK plugin_kmem.so
LINK plugin_kvm.so
LINK plugin_mac80211.so
LINK plugin_sched_switch.so
LINK plugin_function.so
LINK plugin_xen.so
LINK plugin_scsi.so
LINK plugin_cfg80211.so
CC builtin-bench.o
CC builtin-annotate.o
CC builtin-config.o
CC builtin-diff.o
CC util/block-range.o
CC util/build-id.o
CC builtin-evlist.o
CC builtin-ftrace.o
CC util/config.o
CC builtin-help.o
CC builtin-sched.o
CC util/ctype.o
CC util/db-export.o
CC util/env.o
CC util/event.o
CC util/evlist.o
CC builtin-buildid-list.o
CC builtin-buildid-cache.o
CC builtin-kallsyms.o
CC builtin-list.o
CC util/evsel.o
CC builtin-record.o
CC builtin-report.o
CC builtin-stat.o
CC util/evsel_fprintf.o
CC util/find_bit.o
CC util/kallsyms.o
CC util/levenshtein.o
CC util/llvm-utils.o
CC util/mmap.o
CC util/memswap.o
BISON util/parse-events-bison.c
CC builtin-timechart.o
CC util/perf_regs.o
CC util/path.o
CC util/print_binary.o
CC util/rbtree.o
CC util/libstring.o
CC util/bitmap.o
CC util/hweight.o
CC util/smt.o
CC builtin-top.o
CC util/quote.o
CC util/strbuf.o
CC util/string.o
CC util/strlist.o
CC util/strfilter.o
CC builtin-script.o
CC util/top.o
CC util/usage.o
CC util/dso.o
CC util/symbol.o
CC builtin-kmem.o
CC util/symbol_fprintf.o
CC util/color.o
CC builtin-lock.o
CC util/metricgroup.o
CC builtin-kvm.o
CC util/header.o
CC builtin-inject.o
CC builtin-mem.o
CC builtin-data.o
CC builtin-version.o
CC builtin-c2c.o
CC util/callchain.o
CC builtin-trace.o
CC util/values.o
CC util/debug.o
CC util/machine.o
CC builtin-probe.o
CC bench/sched-messaging.o
CC bench/sched-pipe.o
CC util/map.o
CC bench/mem-functions.o
CC bench/futex-hash.o
CC bench/futex-wake.o
CC util/pstack.o
CC bench/futex-wake-parallel.o
CC util/session.o
CC bench/futex-requeue.o
CC bench/futex-lock-pi.o
CC bench/mem-memcpy-x86-64-asm.o
CC bench/mem-memset-x86-64-asm.o
CC bench/numa.o
CC util/syscalltbl.o
CC util/ordered-events.o
CC util/namespaces.o
LD bench/perf-in.o
CC tests/builtin-test.o
CC util/comm.o
CC util/thread.o
CC tests/parse-events.o
CC util/thread_map.o
CC util/trace-event-parse.o
CC util/parse-events-bison.o
CC tests/dso-data.o
BISON util/pmu-bison.c
CC util/trace-event-read.o
CC tests/attr.o
CC util/trace-event-info.o
CC tests/vmlinux-kallsyms.o
CC tests/openat-syscall.o
CC util/trace-event-scripting.o
CC tests/openat-syscall-all-cpus.o
CC util/trace-event.o
CC tests/openat-syscall-tp-fields.o
CC util/svghelper.o
CC tests/mmap-basic.o
CC tests/perf-record.o
CC util/sort.o
CC tests/evsel-roundtrip-name.o
CC tests/evsel-tp-sched.o
CC tests/fdarray.o
CC tests/pmu.o
CC tests/hists_common.o
CC tests/hists_link.o
CC util/hist.o
CC tests/hists_filter.o
CC tests/hists_output.o
CC tests/hists_cumulate.o
CC tests/python-use.o
CC tests/bp_signal.o
CC util/util.o
CC tests/bp_signal_overflow.o
CC tests/bp_account.o
CC util/xyarray.o
CC util/cpumap.o
CC tests/task-exit.o
CC tests/sw-clock.o
CC tests/mmap-thread-lookup.o
CC util/cgroup.o
CC tests/thread-mg-share.o
CC tests/switch-tracking.o
CC util/target.o
CC util/rblist.o
CC util/intlist.o
CC tests/keep-tracking.o
CC util/vdso.o
CC tests/code-reading.o
CC util/counts.o
CC util/stat.o
CC tests/sample-parsing.o
CC util/stat-shadow.o
CC tests/parse-no-sample-id-all.o
CC tests/kmod-path.o
CC tests/thread-map.o
CC tests/llvm.o
CC util/record.o
CC tests/bpf.o
CC util/srcline.o
CC tests/topology.o
CC tests/mem.o
CC util/data.o
CC tests/cpumap.o
CC util/tsc.o
CC util/cloexec.o
CC tests/stat.o
CC util/call-path.o
CC tests/event_update.o
CC util/rwsem.o
CC util/thread-stack.o
CC tests/event-times.o
CC tests/expr.o
CC util/auxtrace.o
CC tests/backward-ring-buffer.o
CC tests/sdt.o
CC tests/is_printable_array.o
CC tests/bitmap.o
CC tests/perf-hooks.o
CC tests/clang.o
CC util/intel-pt-decoder/intel-pt-pkt-decoder.o
CC tests/unit_number__scnprintf.o
CC tests/mem2node.o
GEN util/intel-pt-decoder/inat-tables.c
CC util/intel-pt-decoder/intel-pt-log.o
CC tests/dwarf-unwind.o
CC util/intel-pt-decoder/intel-pt-decoder.o
CC tests/llvm-src-base.o
CC tests/llvm-src-kbuild.o
CC tests/llvm-src-prologue.o
CC tests/llvm-src-relocation.o
LD tests/perf-in.o
CC perf.o
LD perf-in.o
CC util/intel-pt-decoder/intel-pt-insn-decoder.o
LINK pmu-events/jevents
GEN libtraceevent-dynamic-list
GEN pmu-events/pmu-events.c
CC pmu-events/pmu-events.o
LD pmu-events/pmu-events-in.o
GEN python/perf.so
LD util/intel-pt-decoder/libperf-in.o
CC util/scripting-engines/trace-event-python.o
LD util/scripting-engines/libperf-in.o
CC util/intel-pt.o
CC util/intel-bts.o
CC util/arm-spe.o
CC util/arm-spe-pkt-decoder.o
CC util/parse-branch-options.o
CC util/dump-insn.o
CC util/parse-regs-options.o
CC util/term.o
CC util/help-unknown-cmd.o
CC util/mem-events.o
CC util/vsprintf.o
CC util/drv_configs.o
CC util/units.o
CC util/time-utils.o
BISON util/expr-bison.c
CC util/branch.o
CC util/mem2node.o
CC util/bpf-loader.o
CC util/bpf-prologue.o
CC util/symbol-elf.o
CC util/probe-file.o
CC arch/common.o
CC arch/x86/util/header.o
CC arch/x86/util/tsc.o
CC util/probe-event.o
CC arch/x86/util/pmu.o
CC arch/x86/util/kvm-stat.o
CC arch/x86/util/perf_regs.o
CC arch/x86/util/group.o
CC arch/x86/util/dwarf-regs.o
CC arch/x86/util/unwind-libunwind.o
CC arch/x86/util/auxtrace.o
CC arch/x86/util/intel-pt.o
CC arch/x86/util/intel-bts.o
CC util/probe-finder.o
LD arch/x86/util/libperf-in.o
CC arch/x86/tests/regs_load.o
CC arch/x86/tests/dwarf-unwind.o
CC arch/x86/tests/arch-tests.o
CC arch/x86/tests/rdpmc.o
CC arch/x86/tests/perf-time-to-tsc.o
CC arch/x86/tests/insn-x86.o
CC util/dwarf-aux.o
LD arch/x86/tests/libperf-in.o
LD arch/x86/libperf-in.o
LD arch/libperf-in.o
CC ui/setup.o
CC ui/helpline.o
CC ui/progress.o
CC ui/util.o
CC util/dwarf-regs.o
CC ui/hist.o
CC util/unwind-libunwind-local.o
CC util/unwind-libunwind.o
CC util/zlib.o
CC util/lzma.o
CC util/demangle-java.o
CC util/demangle-rust.o
CC util/jitdump.o
CC ui/stdio/hist.o
CC util/genelf.o
CC util/genelf_debug.o
LD ui/libperf-in.o
CC scripts/python/Perf-Trace-Util/Context.o
LD scripts/python/Perf-Trace-Util/libperf-in.o
LD scripts/libperf-in.o
CC trace/beauty/clone.o
CC trace/beauty/fcntl.o
CC util/perf-hooks.o
CC trace/beauty/flock.o
CC trace/beauty/ioctl.o
FLEX util/parse-events-flex.c
FLEX util/pmu-flex.c
CC util/pmu-bison.o
CC trace/beauty/kcmp.o
CC trace/beauty/pkey_alloc.o
CC trace/beauty/prctl.o
CC util/expr-bison.o
CC trace/beauty/statx.o
LD trace/beauty/libperf-in.o
CC util/parse-events.o
CC util/parse-events-flex.o
CC util/pmu.o
CC util/pmu-flex.o
LD util/libperf-in.o
LD libperf-in.o
AR libperf.a
LINK perf
make: Leaving directory '/usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf'
2018-05-22 17:40:51 cd /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf
2018-05-22 17:40:51 mkdir -p /pkg
2018-05-22 17:40:51 mkdir -p /kbuild/obj/lkp/x86_64-fedora-25
2018-05-22 17:40:51 cp /pkg/linux/x86_64-fedora-25/gcc-7/32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/vmlinux.xz /tmp
2018-05-22 17:40:53 unxz -k /tmp/vmlinux.xz
2018-05-22 17:41:00 cp /tmp/vmlinux /kbuild/obj/lkp/x86_64-fedora-25
ignored_by_lkp: BPF filter
ignored_by_lkp: LLVM search and compile
ignored_by_lkp: Add vfs_getname probe to get syscall args filenames
ignored_by_lkp: Use vfs_getname probe to get syscall args filenames
ignored_by_lkp: Check open filename arg using perf trace + vfs_getname
ignored_by_lkp: builtin clang support
2018-05-22 17:41:01 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 1
1: vmlinux symtab matches kallsyms : Ok
2018-05-22 17:41:02 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 2
2: Detect openat syscall event : Ok
2018-05-22 17:41:02 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 3
3: Detect openat syscall event on all cpus : Ok
2018-05-22 17:41:02 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 4
4: Read samples using the mmap interface : Ok
2018-05-22 17:41:02 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 5
5: Test data source output : Ok
2018-05-22 17:41:02 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 6
6: Parse event definition strings : FAILED!
2018-05-22 17:41:03 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 7
7: Simple expression parser : Ok
2018-05-22 17:41:03 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 8
8: PERF_RECORD_* events & perf_sample fields : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 9
9: Parse perf pmu format : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 10
10: DSO data read : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 11
11: DSO data cache : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 12
12: DSO data reopen : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 13
13: Roundtrip evsel->name : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 14
14: Parse sched tracepoints fields : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 15
15: syscalls:sys_enter_openat event fields : Ok
2018-05-22 17:41:05 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 16
16: Setup struct perf_event_attr : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 17
17: Match and link multiple hists : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 18
18: 'import perf' in python : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 19
19: Breakpoint overflow signal handler : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 20
20: Breakpoint overflow sampling : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 21
21: Breakpoint accounting : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 22
22: Number of exit events of a simple workload : Skip
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 23
23: Software clock events period values : Ok
2018-05-22 17:41:11 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 24
24: Object code reading : FAILED!
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 25
25: Sample parsing : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 26
26: Use a dummy software event to keep tracking : Skip
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 27
27: Parse with no sample_id_all bit set : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 28
28: Filter hist entries : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 29
29: Lookup mmap thread : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 30
30: Share thread mg : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 31
31: Sort output of hist entries : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 32
32: Cumulate child hist entries : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 33
33: Track with sched_switch : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 34
34: Filter fds with revents mask in a fdarray : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 35
35: Add fd to a fdarray, making it autogrow : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 36
36: kmod_path__parse : Ok
2018-05-22 17:41:12 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 37
37: Thread map : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 39
39: Session topology : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 41
41: Synthesize thread map : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 42
42: Remove thread map : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 43
43: Synthesize cpu map : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 44
44: Synthesize stat config : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 45
45: Synthesize stat : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 46
46: Synthesize stat round : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 47
47: Synthesize attr update : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 48
48: Event times : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 49
49: Read backward ring buffer : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 50
50: Print cpu map : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 51
51: Probe SDT events : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 52
52: is_printable_array : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 53
53: Print bitmap : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 54
54: perf hooks : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 56
56: unit_number__scnprintf : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 57
57: mem2node : Ok
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 58
58: x86 rdpmc : FAILED!
2018-05-22 17:41:13 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 59
59: Convert perf time to TSC : FAILED!
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 60
60: DWARF unwind : Ok
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 61
61: x86 instruction decoder - new instructions : Ok
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 62
62: probe libc's inet_pton & backtrace it with ping : FAILED!
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 66
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 67
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 68
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 69
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 70
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 71
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 72
2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 73
^ permalink raw reply [flat|nested] 14+ messages in thread
* Re: [lkp-robot] [perf tests] 32a4debc7c: perf-sanity-tests.probe_libc's_inet_pton_&_backtrace_it_with_ping.fail
2018-07-04 6:57 ` kernel test robot
(?)
@ 2018-07-04 12:37 ` Sandipan Das
-1 siblings, 0 replies; 14+ messages in thread
From: Sandipan Das @ 2018-07-04 12:37 UTC (permalink / raw)
To: kernel test robot
Cc: acme, jolsa, linux-kernel, ravi.bangoria, naveen.n.rao, tmricht, lkp
Hi Xiaolong,
On 07/04/2018 12:27 PM, kernel test robot wrote:
>
> FYI, we noticed the following commit (built with gcc-7):
>
> commit: 32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f ("[PATCH] perf tests: Fix regex for record+probe_libc_inet_pton.sh")
> url: https://github.com/0day-ci/linux/commits/Sandipan-Das/perf-tests-Fix-regex-for-record-probe_libc_inet_pton-sh/20180521-130408
>
>
> in testcase: perf-sanity-tests
> with following parameters:
>
> perf_compiler: gcc
>
>
>
> on test machine: qemu-system-x86_64 -enable-kvm -cpu kvm64,+ssse3 -smp 2 -m 8G
>
> caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
>
>
> 2018-05-22 17:41:14 sudo /usr/src/linux-perf-x86_64-fedora-25-32a4debc7c89ee5e29c0bbdfdd3ae0ce0e01057f/tools/perf/perf test 62
> 62: probe libc's inet_pton & backtrace it with ping : FAILED!
>
>
This patch has the following dependencies which seem to be missing from your source tree.
7903a7086723 ("perf script: Show symbol offsets by default")
19610184693c ("perf script: Show virtual addresses instead of offsets")
Output without these patches:
62: probe libc's inet_pton & backtrace it with ping :
--- start ---
test child forked, pid 10323
ping 10340 [001] 888647.764907: probe_libc:inet_pton: (7fe66cbc2220)
132220 __GI___inet_pton (/usr/lib64/libc-2.25.so)
FAIL: expected backtrace entry 1 ".*inet_pton\+0x[[:xdigit:]]+[[:space:]]\(/usr/lib64/libc-2.25.so|inlined\)$" got "132220 __GI___inet_pton (/usr/lib64/libc-2.25.so)"
test child finished with -1
---- end ----
probe libc's inet_pton & backtrace it with ping: FAILED!
Output with these patches:
62: probe libc's inet_pton & backtrace it with ping :
--- start ---
test child forked, pid 13155
ping 13172 [000] 888783.582767: probe_libc:inet_pton: (7f2afe5ce220)
7f2afe5ce220 __GI___inet_pton+0x0 (/usr/lib64/libc-2.25.so)
7f2afe593ebd getaddrinfo+0x11d (/usr/lib64/libc-2.25.so)
55b725fd8c71 main+0x891 (/usr/bin/ping)
test child finished with 0
---- end ----
probe libc's inet_pton & backtrace it with ping: Ok
--
With Regards,
Sandipan
^ permalink raw reply [flat|nested] 14+ messages in thread