linux-kernel - Re: [PATCH 09/14] perf ftrace: Add 'record' sub-command

lists.openwall.net		lists / announce owl-users owl-dev john-users john-dev passwdqc-users yescrypt popa3d-users / oss-security kernel-hardening musl sabotage tlsify passwords / crypt-dev xvendor / Bugtraq Full-Disclosure linux-kernel linux-netdev linux-ext4 linux-hardening linux-cve-announce PHC
Open Source and information security mailing list archives

Hash Suite: Windows password security audit tool. GUI, reports in PDF.

[<prev] [next>] [<thread-prev] [thread-next>] [day] [month] [year] [list]

Message-ID: <20130426083507.GA1054@krava.brq.redhat.com>
Date:	Fri, 26 Apr 2013 10:35:07 +0200
From:	Jiri Olsa <jolsa@...hat.com>
To:	Namhyung Kim <namhyung@...nel.org>
Cc:	Arnaldo Carvalho de Melo <acme@...stprotocols.net>,
	Peter Zijlstra <a.p.zijlstra@...llo.nl>,
	Paul Mackerras <paulus@...ba.org>,
	Ingo Molnar <mingo@...nel.org>,
	Namhyung Kim <namhyung.kim@....com>,
	LKML <linux-kernel@...r.kernel.org>,
	Steven Rostedt <rostedt@...dmis.org>,
	Frederic Weisbecker <fweisbec@...il.com>,
	David Ahern <dsahern@...il.com>,
	Stephane Eranian <eranian@...gle.com>
Subject: Re: [PATCH 09/14] perf ftrace: Add 'record' sub-command

On Thu, Apr 25, 2013 at 03:24:18PM +0900, Namhyung Kim wrote:
> On Wed, 24 Apr 2013 16:12:41 +0200, Jiri Olsa wrote:
> > I got following lockup for record command:
> >
> >   # ./perf --no-pager ftrace record ls
> >   ...
> >   hangs
> >
> >   in other terminal:
> >
> >   # pstack 14237
> >   Thread 2 (Thread 0x7f3f1aa1d700 (LWP 14241)):
> >   #0  0x0000003cec20b595 in pthread_cond_wait@@GLIBC_2.3.2 () from /lib64/libpthread.so.0
> >   #1  0x0000000000446ebe in record_ftrace_raw_buffer (arg=0x21f9ce0) at builtin-ftrace.c:451
> >   #2  0x0000003cec207d14 in start_thread () from /lib64/libpthread.so.0
> >   #3  0x0000003cebaf168d in clone () from /lib64/libc.so.6
> >   Thread 1 (Thread 0x7f3f1ba209c0 (LWP 14237)):
> >   #0  0x0000003cec208e60 in pthread_join () from /lib64/libpthread.so.0
> >   #1  0x0000000000449c8c in do_ftrace_record (ftrace=ftrace@...ry=0x7fff1377d240) at builtin-ftrace.c:682
> >   #2  0x000000000044a57b in __cmd_ftrace_record (argv=0x7fff1377d818, argc=<optimized out>, ftrace=0x7fff1377d240) at builtin-ftrace.c:1535
> >   #3  cmd_ftrace (argc=2, argv=0x7fff1377d818, prefix=<optimized out>) at builtin-ftrace.c:1655
> >   #4  0x000000000041a763 in run_builtin (p=p@...ry=0x7d58a0, argc=argc@...ry=3, argv=argv@...ry=0x7fff1377d818) at perf.c:320
> >   #5  0x0000000000419faf in handle_internal_command (argv=0x7fff1377d818, argc=3) at perf.c:377
> >   #6  run_argv (argv=0x7fff1377d600, argcp=0x7fff1377d60c) at perf.c:421
> >   #7  main (argc=3, argv=0x7fff1377d818) at perf.c:522
> >
> 
> How often can you reproduce it?  I can't. :(

yesterday it was more often than today ;-) but I still get it
after several runs, this reproduces it for me:

  # while [ 1 ]; do ./perf --no-pager ftrace record ls ; done

seems like there's no change in backtraces:

[root@...va tracing]# pstack 4139
Thread 2 (Thread 0x7f4aa37fe700 (LWP 4145)):
#0  0x0000003cec20b595 in pthread_cond_wait@@GLIBC_2.3.2 () from /lib64/libpthread.so.0
#1  0x0000000000446ebe in record_ftrace_raw_buffer (arg=0xe10e90) at builtin-ftrace.c:451
#2  0x0000003cec207d14 in start_thread () from /lib64/libpthread.so.0
#3  0x0000003cebaf168d in clone () from /lib64/libc.so.6
Thread 1 (Thread 0x7f4aac29c9c0 (LWP 4139)):
#0  0x0000003cec208e60 in pthread_join () from /lib64/libpthread.so.0
#1  0x0000000000449c8c in do_ftrace_record (ftrace=ftrace@...ry=0x7fffaf9bc080) at builtin-ftrace.c:682
#2  0x000000000044a57b in __cmd_ftrace_record (argv=0x7fffaf9bc658, argc=<optimized out>, ftrace=0x7fffaf9bc080) at builtin-ftrace.c:1535
#3  cmd_ftrace (argc=2, argv=0x7fffaf9bc658, prefix=<optimized out>) at builtin-ftrace.c:1655
#4  0x000000000041a763 in run_builtin (p=p@...ry=0x7d58a0, argc=argc@...ry=3, argv=argv@...ry=0x7fffaf9bc658) at perf.c:320
#5  0x0000000000419faf in handle_internal_command (argv=0x7fffaf9bc658, argc=3) at perf.c:377
#6  run_argv (argv=0x7fffaf9bc440, argcp=0x7fffaf9bc44c) at perf.c:421
#7  main (argc=3, argv=0x7fffaf9bc658) at perf.c:522

---

#0  0x0000003cec20b595 in pthread_cond_wait@@GLIBC_2.3.2 () from /lib64/libpthread.so.0
#1  0x0000000000446ebe in record_ftrace_raw_buffer (arg=0xee9ce0) at builtin-ftrace.c:451
#2  0x0000003cec207d14 in start_thread () from /lib64/libpthread.so.0
#3  0x0000003cebaf168d in clone () from /lib64/libc.so.6
Thread 1 (Thread 0x7f9b383139c0 (LWP 4397)):
#0  0x0000003cec208e60 in pthread_join () from /lib64/libpthread.so.0
#1  0x0000000000449c8c in do_ftrace_record (ftrace=ftrace@...ry=0x7fffdea14e50) at builtin-ftrace.c:682
#2  0x000000000044a57b in __cmd_ftrace_record (argv=0x7fffdea15428, argc=<optimized out>, ftrace=0x7fffdea14e50) at builtin-ftrace.c:1535
#3  cmd_ftrace (argc=2, argv=0x7fffdea15428, prefix=<optimized out>) at builtin-ftrace.c:1655
#4  0x000000000041a763 in run_builtin (p=p@...ry=0x7d58a0, argc=argc@...ry=3, argv=argv@...ry=0x7fffdea15428) at perf.c:320
#5  0x0000000000419faf in handle_internal_command (argv=0x7fffdea15428, argc=3) at perf.c:377
#6  run_argv (argv=0x7fffdea15210, argcp=0x7fffdea1521c) at perf.c:421
#7  main (argc=3, argv=0x7fffdea15428) at perf.c:522


jirka
--
To unsubscribe from this list: send the line "unsubscribe linux-kernel" in
the body of a message to majordomo@...r.kernel.org
More majordomo info at  http://vger.kernel.org/majordomo-info.html
Please read the FAQ at  http://www.tux.org/lkml/