* [PATCH v2] Unbuffered output when pipe/tee to a file @ 2021-11-19 6:14 Sohaib Mohamed 2021-11-28 15:34 ` Jiri Olsa 2021-12-09 18:50 ` Arnaldo Carvalho de Melo 0 siblings, 2 replies; 6+ messages in thread From: Sohaib Mohamed @ 2021-11-19 6:14 UTC (permalink / raw) Cc: sohaib.amhmd, irogers, Riccardo Mancini, Peter Zijlstra, Ingo Molnar, Arnaldo Carvalho de Melo, Mark Rutland, Alexander Shishkin, Jiri Olsa, Namhyung Kim, Adrian Hunter, Fabian Hemmer, linux-perf-users, linux-kernel The output of Perf bench gets buffered when I pipe it to a file or to tee, in such a way that I can see it only at the end. E.g. $ perf bench internals synthesize -t < output comes out fine after each test run > $ perf bench internals synthesize -t | tee file.txt < output comes out only at the end of all tests > This patch resolves this issue for 'bench' and 'test' subcommands. See, also: $ perf bench mem all | tee file.txt $ perf bench sched all | tee file.txt $ perf bench internals all -t | tee file.txt $ perf bench internals all | tee file.txt Suggested-by: Riccardo Mancini <rickyman7@gmail.com> Signed-off-by: Sohaib Mohamed <sohaib.amhmd@gmail.com> --- v1 -> v2: - Use setvbuf(), instead of sprinkling fflush() calls and missing some places. v1: https://lore.kernel.org/linux-perf-users/20211112215313.108823-1-sohaib.amhmd@gmail.com/ --- tools/perf/builtin-bench.c | 5 +++-- tools/perf/tests/builtin-test.c | 3 +++ 2 files changed, 6 insertions(+), 2 deletions(-) diff --git a/tools/perf/builtin-bench.c b/tools/perf/builtin-bench.c index d0895162c2ba..d291f3a8af5f 100644 --- a/tools/perf/builtin-bench.c +++ b/tools/perf/builtin-bench.c @@ -226,7 +226,6 @@ static void run_collection(struct collection *coll) if (!bench->fn) break; printf("# Running %s/%s benchmark...\n", coll->name, bench->name); - fflush(stdout); argv[1] = bench->name; run_bench(coll->name, bench->name, bench->fn, 1, argv); @@ -247,6 +246,9 @@ int cmd_bench(int argc, const char **argv) struct collection *coll; int ret = 0; + /* Unbuffered output */ + setvbuf(stdout, NULL, _IONBF, 0); + if (argc < 2) { /* No collection specified. */ print_usage(); @@ -300,7 +302,6 @@ int cmd_bench(int argc, const char **argv) if (bench_format == BENCH_FORMAT_DEFAULT) printf("# Running '%s/%s' benchmark:\n", coll->name, bench->name); - fflush(stdout); ret = run_bench(coll->name, bench->name, bench->fn, argc-1, argv+1); goto end; } diff --git a/tools/perf/tests/builtin-test.c b/tools/perf/tests/builtin-test.c index 8cb5a1c3489e..d92ae4efd2e6 100644 --- a/tools/perf/tests/builtin-test.c +++ b/tools/perf/tests/builtin-test.c @@ -606,6 +606,9 @@ int cmd_test(int argc, const char **argv) if (ret < 0) return ret; + /* Unbuffered output */ + setvbuf(stdout, NULL, _IONBF, 0); + argc = parse_options_subcommand(argc, argv, test_options, test_subcommands, test_usage, 0); if (argc >= 1 && !strcmp(argv[0], "list")) return perf_test__list(argc - 1, argv + 1); -- 2.25.1 ^ permalink raw reply related [flat|nested] 6+ messages in thread
* Re: [PATCH v2] Unbuffered output when pipe/tee to a file 2021-11-19 6:14 [PATCH v2] Unbuffered output when pipe/tee to a file Sohaib Mohamed @ 2021-11-28 15:34 ` Jiri Olsa 2021-12-09 18:51 ` Arnaldo Carvalho de Melo 2021-12-09 18:50 ` Arnaldo Carvalho de Melo 1 sibling, 1 reply; 6+ messages in thread From: Jiri Olsa @ 2021-11-28 15:34 UTC (permalink / raw) To: Sohaib Mohamed Cc: irogers, Riccardo Mancini, Peter Zijlstra, Ingo Molnar, Arnaldo Carvalho de Melo, Mark Rutland, Alexander Shishkin, Namhyung Kim, Adrian Hunter, Fabian Hemmer, linux-perf-users, linux-kernel On Fri, Nov 19, 2021 at 08:14:08AM +0200, Sohaib Mohamed wrote: > The output of Perf bench gets buffered when I pipe it to a file or to > tee, in such a way that I can see it only at the end. > > E.g. > $ perf bench internals synthesize -t > < output comes out fine after each test run > > > $ perf bench internals synthesize -t | tee file.txt > < output comes out only at the end of all tests > > > This patch resolves this issue for 'bench' and 'test' subcommands. I can reproduce this for bench, but not for test subcommand other that that it makes sense to me jirka > > See, also: > $ perf bench mem all | tee file.txt > $ perf bench sched all | tee file.txt > $ perf bench internals all -t | tee file.txt > $ perf bench internals all | tee file.txt > > Suggested-by: Riccardo Mancini <rickyman7@gmail.com> > Signed-off-by: Sohaib Mohamed <sohaib.amhmd@gmail.com> > --- > v1 -> v2: > - Use setvbuf(), instead of sprinkling fflush() calls and missing some places. > > v1: https://lore.kernel.org/linux-perf-users/20211112215313.108823-1-sohaib.amhmd@gmail.com/ > --- > tools/perf/builtin-bench.c | 5 +++-- > tools/perf/tests/builtin-test.c | 3 +++ > 2 files changed, 6 insertions(+), 2 deletions(-) > > diff --git a/tools/perf/builtin-bench.c b/tools/perf/builtin-bench.c > index d0895162c2ba..d291f3a8af5f 100644 > --- a/tools/perf/builtin-bench.c > +++ b/tools/perf/builtin-bench.c > @@ -226,7 +226,6 @@ static void run_collection(struct collection *coll) > if (!bench->fn) > break; > printf("# Running %s/%s benchmark...\n", coll->name, bench->name); > - fflush(stdout); > > argv[1] = bench->name; > run_bench(coll->name, bench->name, bench->fn, 1, argv); > @@ -247,6 +246,9 @@ int cmd_bench(int argc, const char **argv) > struct collection *coll; > int ret = 0; > > + /* Unbuffered output */ > + setvbuf(stdout, NULL, _IONBF, 0); > + > if (argc < 2) { > /* No collection specified. */ > print_usage(); > @@ -300,7 +302,6 @@ int cmd_bench(int argc, const char **argv) > > if (bench_format == BENCH_FORMAT_DEFAULT) > printf("# Running '%s/%s' benchmark:\n", coll->name, bench->name); > - fflush(stdout); > ret = run_bench(coll->name, bench->name, bench->fn, argc-1, argv+1); > goto end; > } > diff --git a/tools/perf/tests/builtin-test.c b/tools/perf/tests/builtin-test.c > index 8cb5a1c3489e..d92ae4efd2e6 100644 > --- a/tools/perf/tests/builtin-test.c > +++ b/tools/perf/tests/builtin-test.c > @@ -606,6 +606,9 @@ int cmd_test(int argc, const char **argv) > if (ret < 0) > return ret; > > + /* Unbuffered output */ > + setvbuf(stdout, NULL, _IONBF, 0); > + > argc = parse_options_subcommand(argc, argv, test_options, test_subcommands, test_usage, 0); > if (argc >= 1 && !strcmp(argv[0], "list")) > return perf_test__list(argc - 1, argv + 1); > -- > 2.25.1 > ^ permalink raw reply [flat|nested] 6+ messages in thread
* Re: [PATCH v2] Unbuffered output when pipe/tee to a file 2021-11-28 15:34 ` Jiri Olsa @ 2021-12-09 18:51 ` Arnaldo Carvalho de Melo 2021-12-09 19:30 ` Jiri Olsa 0 siblings, 1 reply; 6+ messages in thread From: Arnaldo Carvalho de Melo @ 2021-12-09 18:51 UTC (permalink / raw) To: Jiri Olsa Cc: Sohaib Mohamed, irogers, Riccardo Mancini, Peter Zijlstra, Ingo Molnar, Mark Rutland, Alexander Shishkin, Namhyung Kim, Adrian Hunter, Fabian Hemmer, linux-perf-users, linux-kernel Em Sun, Nov 28, 2021 at 04:34:05PM +0100, Jiri Olsa escreveu: > On Fri, Nov 19, 2021 at 08:14:08AM +0200, Sohaib Mohamed wrote: > > The output of Perf bench gets buffered when I pipe it to a file or to > > tee, in such a way that I can see it only at the end. > > > > E.g. > > $ perf bench internals synthesize -t > > < output comes out fine after each test run > > > > > $ perf bench internals synthesize -t | tee file.txt > > < output comes out only at the end of all tests > > > > > This patch resolves this issue for 'bench' and 'test' subcommands. > > I can reproduce this for bench, but not for test subcommand > other that that it makes sense to me I reproduced it, see my comment to Sohaib's message, its not at the end, its when the buffer fills up. From the "it makes sense to me" I'm deriving an Acked-by you, ok? - Arnaldo > jirka > > > > > See, also: > > $ perf bench mem all | tee file.txt > > $ perf bench sched all | tee file.txt > > $ perf bench internals all -t | tee file.txt > > $ perf bench internals all | tee file.txt > > > > Suggested-by: Riccardo Mancini <rickyman7@gmail.com> > > Signed-off-by: Sohaib Mohamed <sohaib.amhmd@gmail.com> > > --- > > v1 -> v2: > > - Use setvbuf(), instead of sprinkling fflush() calls and missing some places. > > > > v1: https://lore.kernel.org/linux-perf-users/20211112215313.108823-1-sohaib.amhmd@gmail.com/ > > --- > > tools/perf/builtin-bench.c | 5 +++-- > > tools/perf/tests/builtin-test.c | 3 +++ > > 2 files changed, 6 insertions(+), 2 deletions(-) > > > > diff --git a/tools/perf/builtin-bench.c b/tools/perf/builtin-bench.c > > index d0895162c2ba..d291f3a8af5f 100644 > > --- a/tools/perf/builtin-bench.c > > +++ b/tools/perf/builtin-bench.c > > @@ -226,7 +226,6 @@ static void run_collection(struct collection *coll) > > if (!bench->fn) > > break; > > printf("# Running %s/%s benchmark...\n", coll->name, bench->name); > > - fflush(stdout); > > > > argv[1] = bench->name; > > run_bench(coll->name, bench->name, bench->fn, 1, argv); > > @@ -247,6 +246,9 @@ int cmd_bench(int argc, const char **argv) > > struct collection *coll; > > int ret = 0; > > > > + /* Unbuffered output */ > > + setvbuf(stdout, NULL, _IONBF, 0); > > + > > if (argc < 2) { > > /* No collection specified. */ > > print_usage(); > > @@ -300,7 +302,6 @@ int cmd_bench(int argc, const char **argv) > > > > if (bench_format == BENCH_FORMAT_DEFAULT) > > printf("# Running '%s/%s' benchmark:\n", coll->name, bench->name); > > - fflush(stdout); > > ret = run_bench(coll->name, bench->name, bench->fn, argc-1, argv+1); > > goto end; > > } > > diff --git a/tools/perf/tests/builtin-test.c b/tools/perf/tests/builtin-test.c > > index 8cb5a1c3489e..d92ae4efd2e6 100644 > > --- a/tools/perf/tests/builtin-test.c > > +++ b/tools/perf/tests/builtin-test.c > > @@ -606,6 +606,9 @@ int cmd_test(int argc, const char **argv) > > if (ret < 0) > > return ret; > > > > + /* Unbuffered output */ > > + setvbuf(stdout, NULL, _IONBF, 0); > > + > > argc = parse_options_subcommand(argc, argv, test_options, test_subcommands, test_usage, 0); > > if (argc >= 1 && !strcmp(argv[0], "list")) > > return perf_test__list(argc - 1, argv + 1); > > -- > > 2.25.1 > > -- - Arnaldo ^ permalink raw reply [flat|nested] 6+ messages in thread
* Re: [PATCH v2] Unbuffered output when pipe/tee to a file 2021-12-09 18:51 ` Arnaldo Carvalho de Melo @ 2021-12-09 19:30 ` Jiri Olsa 0 siblings, 0 replies; 6+ messages in thread From: Jiri Olsa @ 2021-12-09 19:30 UTC (permalink / raw) To: Arnaldo Carvalho de Melo Cc: Sohaib Mohamed, irogers, Riccardo Mancini, Peter Zijlstra, Ingo Molnar, Mark Rutland, Alexander Shishkin, Namhyung Kim, Adrian Hunter, Fabian Hemmer, linux-perf-users, linux-kernel On Thu, Dec 09, 2021 at 03:51:37PM -0300, Arnaldo Carvalho de Melo wrote: > Em Sun, Nov 28, 2021 at 04:34:05PM +0100, Jiri Olsa escreveu: > > On Fri, Nov 19, 2021 at 08:14:08AM +0200, Sohaib Mohamed wrote: > > > The output of Perf bench gets buffered when I pipe it to a file or to > > > tee, in such a way that I can see it only at the end. > > > > > > E.g. > > > $ perf bench internals synthesize -t > > > < output comes out fine after each test run > > > > > > > $ perf bench internals synthesize -t | tee file.txt > > > < output comes out only at the end of all tests > > > > > > > This patch resolves this issue for 'bench' and 'test' subcommands. > > > > I can reproduce this for bench, but not for test subcommand > > other that that it makes sense to me > > I reproduced it, see my comment to Sohaib's message, its not at the end, > its when the buffer fills up. > > From the "it makes sense to me" I'm deriving an Acked-by you, ok? ok, yes Acked-by: Jiri Olsa <jolsa@redhat.com> jirka > > - Arnaldo > > > jirka > > > > > > > > See, also: > > > $ perf bench mem all | tee file.txt > > > $ perf bench sched all | tee file.txt > > > $ perf bench internals all -t | tee file.txt > > > $ perf bench internals all | tee file.txt > > > > > > Suggested-by: Riccardo Mancini <rickyman7@gmail.com> > > > Signed-off-by: Sohaib Mohamed <sohaib.amhmd@gmail.com> > > > --- > > > v1 -> v2: > > > - Use setvbuf(), instead of sprinkling fflush() calls and missing some places. > > > > > > v1: https://lore.kernel.org/linux-perf-users/20211112215313.108823-1-sohaib.amhmd@gmail.com/ > > > --- > > > tools/perf/builtin-bench.c | 5 +++-- > > > tools/perf/tests/builtin-test.c | 3 +++ > > > 2 files changed, 6 insertions(+), 2 deletions(-) > > > > > > diff --git a/tools/perf/builtin-bench.c b/tools/perf/builtin-bench.c > > > index d0895162c2ba..d291f3a8af5f 100644 > > > --- a/tools/perf/builtin-bench.c > > > +++ b/tools/perf/builtin-bench.c > > > @@ -226,7 +226,6 @@ static void run_collection(struct collection *coll) > > > if (!bench->fn) > > > break; > > > printf("# Running %s/%s benchmark...\n", coll->name, bench->name); > > > - fflush(stdout); > > > > > > argv[1] = bench->name; > > > run_bench(coll->name, bench->name, bench->fn, 1, argv); > > > @@ -247,6 +246,9 @@ int cmd_bench(int argc, const char **argv) > > > struct collection *coll; > > > int ret = 0; > > > > > > + /* Unbuffered output */ > > > + setvbuf(stdout, NULL, _IONBF, 0); > > > + > > > if (argc < 2) { > > > /* No collection specified. */ > > > print_usage(); > > > @@ -300,7 +302,6 @@ int cmd_bench(int argc, const char **argv) > > > > > > if (bench_format == BENCH_FORMAT_DEFAULT) > > > printf("# Running '%s/%s' benchmark:\n", coll->name, bench->name); > > > - fflush(stdout); > > > ret = run_bench(coll->name, bench->name, bench->fn, argc-1, argv+1); > > > goto end; > > > } > > > diff --git a/tools/perf/tests/builtin-test.c b/tools/perf/tests/builtin-test.c > > > index 8cb5a1c3489e..d92ae4efd2e6 100644 > > > --- a/tools/perf/tests/builtin-test.c > > > +++ b/tools/perf/tests/builtin-test.c > > > @@ -606,6 +606,9 @@ int cmd_test(int argc, const char **argv) > > > if (ret < 0) > > > return ret; > > > > > > + /* Unbuffered output */ > > > + setvbuf(stdout, NULL, _IONBF, 0); > > > + > > > argc = parse_options_subcommand(argc, argv, test_options, test_subcommands, test_usage, 0); > > > if (argc >= 1 && !strcmp(argv[0], "list")) > > > return perf_test__list(argc - 1, argv + 1); > > > -- > > > 2.25.1 > > > > > -- > > - Arnaldo > ^ permalink raw reply [flat|nested] 6+ messages in thread
* Re: [PATCH v2] Unbuffered output when pipe/tee to a file 2021-11-19 6:14 [PATCH v2] Unbuffered output when pipe/tee to a file Sohaib Mohamed 2021-11-28 15:34 ` Jiri Olsa @ 2021-12-09 18:50 ` Arnaldo Carvalho de Melo 2021-12-11 17:10 ` David Laight 1 sibling, 1 reply; 6+ messages in thread From: Arnaldo Carvalho de Melo @ 2021-12-09 18:50 UTC (permalink / raw) To: Sohaib Mohamed Cc: irogers, Riccardo Mancini, Peter Zijlstra, Ingo Molnar, Mark Rutland, Alexander Shishkin, Jiri Olsa, Namhyung Kim, Adrian Hunter, Fabian Hemmer, linux-perf-users, linux-kernel Em Fri, Nov 19, 2021 at 08:14:08AM +0200, Sohaib Mohamed escreveu: > The output of Perf bench gets buffered when I pipe it to a file or to > tee, in such a way that I can see it only at the end. Humm, not at the end, the output is staggered, i.e. only when the buffer fills up it is drained and then we have to wait for it to fill up again, rinse repeat. I just tested and applied it, adding a note about this patch fixing the previous output bursty behaviour. - Arnaldo > E.g. > $ perf bench internals synthesize -t > < output comes out fine after each test run > > > $ perf bench internals synthesize -t | tee file.txt > < output comes out only at the end of all tests > > > This patch resolves this issue for 'bench' and 'test' subcommands. > > See, also: > $ perf bench mem all | tee file.txt > $ perf bench sched all | tee file.txt > $ perf bench internals all -t | tee file.txt > $ perf bench internals all | tee file.txt > > Suggested-by: Riccardo Mancini <rickyman7@gmail.com> > Signed-off-by: Sohaib Mohamed <sohaib.amhmd@gmail.com> > --- > v1 -> v2: > - Use setvbuf(), instead of sprinkling fflush() calls and missing some places. > > v1: https://lore.kernel.org/linux-perf-users/20211112215313.108823-1-sohaib.amhmd@gmail.com/ > --- > tools/perf/builtin-bench.c | 5 +++-- > tools/perf/tests/builtin-test.c | 3 +++ > 2 files changed, 6 insertions(+), 2 deletions(-) > > diff --git a/tools/perf/builtin-bench.c b/tools/perf/builtin-bench.c > index d0895162c2ba..d291f3a8af5f 100644 > --- a/tools/perf/builtin-bench.c > +++ b/tools/perf/builtin-bench.c > @@ -226,7 +226,6 @@ static void run_collection(struct collection *coll) > if (!bench->fn) > break; > printf("# Running %s/%s benchmark...\n", coll->name, bench->name); > - fflush(stdout); > > argv[1] = bench->name; > run_bench(coll->name, bench->name, bench->fn, 1, argv); > @@ -247,6 +246,9 @@ int cmd_bench(int argc, const char **argv) > struct collection *coll; > int ret = 0; > > + /* Unbuffered output */ > + setvbuf(stdout, NULL, _IONBF, 0); > + > if (argc < 2) { > /* No collection specified. */ > print_usage(); > @@ -300,7 +302,6 @@ int cmd_bench(int argc, const char **argv) > > if (bench_format == BENCH_FORMAT_DEFAULT) > printf("# Running '%s/%s' benchmark:\n", coll->name, bench->name); > - fflush(stdout); > ret = run_bench(coll->name, bench->name, bench->fn, argc-1, argv+1); > goto end; > } > diff --git a/tools/perf/tests/builtin-test.c b/tools/perf/tests/builtin-test.c > index 8cb5a1c3489e..d92ae4efd2e6 100644 > --- a/tools/perf/tests/builtin-test.c > +++ b/tools/perf/tests/builtin-test.c > @@ -606,6 +606,9 @@ int cmd_test(int argc, const char **argv) > if (ret < 0) > return ret; > > + /* Unbuffered output */ > + setvbuf(stdout, NULL, _IONBF, 0); > + > argc = parse_options_subcommand(argc, argv, test_options, test_subcommands, test_usage, 0); > if (argc >= 1 && !strcmp(argv[0], "list")) > return perf_test__list(argc - 1, argv + 1); > -- > 2.25.1 -- - Arnaldo ^ permalink raw reply [flat|nested] 6+ messages in thread
* RE: [PATCH v2] Unbuffered output when pipe/tee to a file 2021-12-09 18:50 ` Arnaldo Carvalho de Melo @ 2021-12-11 17:10 ` David Laight 0 siblings, 0 replies; 6+ messages in thread From: David Laight @ 2021-12-11 17:10 UTC (permalink / raw) To: 'Arnaldo Carvalho de Melo', Sohaib Mohamed Cc: irogers, Riccardo Mancini, Peter Zijlstra, Ingo Molnar, Mark Rutland, Alexander Shishkin, Jiri Olsa, Namhyung Kim, Adrian Hunter, Fabian Hemmer, linux-perf-users, linux-kernel From: Arnaldo Carvalho de Melo > Sent: 09 December 2021 18:51 ... > > diff --git a/tools/perf/tests/builtin-test.c b/tools/perf/tests/builtin-test.c > > index 8cb5a1c3489e..d92ae4efd2e6 100644 > > --- a/tools/perf/tests/builtin-test.c > > +++ b/tools/perf/tests/builtin-test.c > > @@ -606,6 +606,9 @@ int cmd_test(int argc, const char **argv) > > if (ret < 0) > > return ret; > > > > + /* Unbuffered output */ > > + setvbuf(stdout, NULL, _IONBF, 0); > > + You only want _IOLBF (line buffered). Otherwise you are likely to get a lot of write() system calls. Even multiple ones from the same printf(). Although it can be better just to ensure there is a fflush(stdout) inside the loop. David - Registered Address Lakeside, Bramley Road, Mount Farm, Milton Keynes, MK1 1PT, UK Registration No: 1397386 (Wales) ^ permalink raw reply [flat|nested] 6+ messages in thread
end of thread, other threads:[~2021-12-11 17:10 UTC | newest] Thread overview: 6+ messages (download: mbox.gz / follow: Atom feed) -- links below jump to the message on this page -- 2021-11-19 6:14 [PATCH v2] Unbuffered output when pipe/tee to a file Sohaib Mohamed 2021-11-28 15:34 ` Jiri Olsa 2021-12-09 18:51 ` Arnaldo Carvalho de Melo 2021-12-09 19:30 ` Jiri Olsa 2021-12-09 18:50 ` Arnaldo Carvalho de Melo 2021-12-11 17:10 ` David Laight
This is an external index of several public inboxes, see mirroring instructions on how to clone and mirror all data and code used by this external index.