Make the --verbose output be valid TAP, making it machine-readable for TAP parsers again. Both the verbose and non-verbose test outputs were valid TAP back when I added support for TAP in 5099b99d25f (test-lib: Adjust output to be valid TAP format, 2010-06-24). Sometime after that the --verbose output broke due to some tests emitting their own lines starting "ok" (or otherwise invalidate the TAP). That was noticed and fixed in 452320f1f5 (test-lib: add --verbose-log option, 2016-10-21) and "fixed" by simply turning off the verbose mode when we were running under TAP::Harness (e.g. under "prove"). That solution worked for running under Travis CI. After that fix it was made to use the --verbose-log option in 041c72de109 (travis: use --verbose-log test option, 2016-10-21), see 522354d70f4 (Add Travis CI support, 2015-11-27) for the "cat t/test-results/*.out" code that was aimed at. But that solution and others discussed in 452320f1f5 closed the door on us having reliable machine-readable TAP output. Let's instead revert the work done in 452320f1f5 and, as well as the follow-up commits 88c6e9d31c (test-lib: --valgrind should not override --verbose-log, 2017-09-05) and f5ba2de6bc (test-lib: make "-x" work with "--verbose-log", 2017-12-08), which were only needed to work around bugs in the the previous --verbose-log implementation. Replace it with a simple method for ensuring that we have valid TAP both on stdout, and in any verbose output we write. When we detect that we're running under "prove" we prefix all legitimate TAP directives with "GIT_TEST_TEE_STARTED": $ GIT_TEST_TEE_STARTED=1 ./t5547-push-quarantine.sh GIT_TEST_TEE_STARTED ok 1 - create picky dest repo GIT_TEST_TEE_STARTED ok 2 - accepted objects work [...] GIT_TEST_TEE_STARTED 1..6 Then, instead of piping the output to "tee -a" we pipe it to a helper which first converts "ok" and other TAP syntax to e.g. "\ok", and then strips that "GIT_TEST_TEE_STARTED " prefix from the start of the line. The end result is that we're guaranteed to have valid TAP syntax on stdout. We can thus get rid of the --verbose-log special-case. Since that option was meant to get around the TAP issue let's simply make it an alias for "--verbose --tee". Signed-off-by: Ævar Arnfjörð Bjarmason <avarab@xxxxxxxxx> --- t/README | 9 ++++++--- t/t0000-basic.sh | 8 ++++++++ t/test-lib.sh | 43 +++++++++++++++++++++---------------------- 3 files changed, 35 insertions(+), 25 deletions(-) diff --git a/t/README b/t/README index 2cc8cbc7185..f09d94e754e 100644 --- a/t/README +++ b/t/README @@ -157,10 +157,13 @@ appropriately before running "make". Short options can be bundled, i.e. -V:: --verbose-log:: - Write verbose output to the same logfile as `--tee`, but do - _not_ write it to stdout. Unlike `--tee --verbose`, this option + An alias for `--verbose --tee`. This option is safe to use when stdout is being consumed by a TAP parser - like `prove`. Implies `--tee` and `--verbose`. + like `prove`. + Historically this option was different from `--verbose --tee` + and would not write any verbose output to stdout to ensure the + TAP-correctness of the output. The TAP-correctness of the + output is now sanity checked by the test library, --with-dashes:: By default tests are run without dashed forms of diff --git a/t/t0000-basic.sh b/t/t0000-basic.sh index 705d62cc27a..2a95a612fb0 100755 --- a/t/t0000-basic.sh +++ b/t/t0000-basic.sh @@ -76,6 +76,14 @@ _run_sub_test_lib_test_common () { # this variable, so we need a stable setting under which to run # the sub-test. sane_unset HARNESS_ACTIVE && + + # When under --tee pretend we're not as far as + # emitting the "GIT_TEST_TEE_STARTED " TAP sanity + # prefix goes. This means we promise not to emit + # invalid TAP, least this test fails under --tee under + # prove(1). + sane_unset GIT_TEST_TEE_STARTED && + cd "$name" && write_script "$name.sh" "$TEST_SHELL_PATH" <<-EOF && test_description='$descr (run in sub test-lib) diff --git a/t/test-lib.sh b/t/test-lib.sh index aa7068b06b6..0070d05234b 100644 --- a/t/test-lib.sh +++ b/t/test-lib.sh @@ -146,9 +146,11 @@ parse_option () { --no-chain-lint) GIT_TEST_CHAIN_LINT=0 ;; -x) - trace=t ;; + trace=t + verbose=t + ;; -V|--verbose-log) - verbose_log=t + verbose=t tee=t ;; --write-junit-xml) @@ -233,7 +235,7 @@ then test -z "$verbose" && verbose_only="$valgrind_only" elif test -n "$valgrind" then - test -z "$verbose_log" && verbose=t + verbose=t fi if test -n "$stress" @@ -364,9 +366,22 @@ then ( GIT_TEST_TEE_STARTED=done ${TEST_SHELL_PATH} "$0" "$@" 2>&1 echo $? >"$TEST_RESULTS_BASE.exit" - ) | tee -a "$GIT_TEST_TEE_OUTPUT_FILE" + ) | "$GIT_BUILD_DIR"/t/helper/test-tool tee \ + --tap --prefix="GIT_TEST_TEE_STARTED " \ + --escape-stdout ${HARNESS_ACTIVE+--escape-file} \ + "$GIT_TEST_TEE_OUTPUT_FILE" test "$(cat "$TEST_RESULTS_BASE.exit")" = 0 exit +elif test -n "$verbose" -a -n "$HARNESS_ACTIVE" +then + ret= + ( + GIT_TEST_TEE_STARTED=done ${TEST_SHELL_PATH} "$0" "$@" 2>&1 + ret=$? + ) | "$GIT_BUILD_DIR"/t/helper/test-tool tee \ + --tap --prefix="GIT_TEST_TEE_STARTED " \ + --escape-stdout + exit $ret fi if test -n "$trace" && test -n "$test_untraceable" @@ -392,10 +407,6 @@ then trace= fi fi -if test -n "$trace" && test -z "$verbose_log" -then - verbose=t -fi # For repeatability, reset the environment to known value. # TERM is sanitized below, after saving color control sequences. @@ -569,7 +580,7 @@ say_color_tap() { test "$#" = 2 || BUG "not 2 parameters to say_color_tap" - say_color "$1" "$2" + say_color "$1" "${GIT_TEST_TEE_STARTED:+GIT_TEST_TEE_STARTED }$2" } TERM=dumb @@ -590,16 +601,6 @@ say () { say_color info "$*" } -if test -n "$HARNESS_ACTIVE" -then - if test "$verbose" = t || test -n "$verbose_only" - then - printf 'Bail out! %s\n' \ - 'verbose mode forbidden under TAP harness; try --verbose-log' - exit 1 - fi -fi - test "${test_description}" != "" || error "Test script did not set test_description." @@ -887,9 +888,7 @@ maybe_setup_valgrind () { trace_level_=0 want_trace () { - test "$trace" = t && { - test "$verbose" = t || test "$verbose_log" = t - } + test "$trace" = t && test "$verbose" = t } # This is a separate function because some tests use -- 2.31.0.rc1.210.g0f8085a843c