test-logtoprocess.t
122 lines
| 3.4 KiB
| text/troff
|
Tads3Lexer
/ tests / test-logtoprocess.t
|
r32915 | #require no-windows | ||
|
r30991 | ATTENTION: logtoprocess runs commands asynchronously. Be sure to append "| cat" | ||
to hg commands, to wait for the output, if you want to test its output. | ||||
Otherwise the test will be flaky. | ||||
|
r28901 | Test if logtoprocess correctly captures command-related log calls. | ||
$ hg init | ||||
$ cat > $TESTTMP/foocommand.py << EOF | ||||
|
r32337 | > from mercurial import registrar | ||
|
r28901 | > cmdtable = {} | ||
|
r32337 | > command = registrar.command(cmdtable) | ||
|
r34765 | > configtable = {} | ||
> configitem = registrar.configitem(configtable) | ||||
|
r40655 | > configitem(b'logtoprocess', b'foo', | ||
|
r34765 | > default=None, | ||
> ) | ||||
|
r40438 | > @command(b'foobar', []) | ||
|
r28901 | > def foo(ui, repo): | ||
|
r40655 | > ui.log(b'foo', b'a message: %s\n', b'spam') | ||
|
r28901 | > EOF | ||
|
r30976 | $ cp $HGRCPATH $HGRCPATH.bak | ||
|
r28901 | $ cat >> $HGRCPATH << EOF | ||
> [extensions] | ||||
> logtoprocess= | ||||
> foocommand=$TESTTMP/foocommand.py | ||||
> [logtoprocess] | ||||
|
r39961 | > command=(echo 'logtoprocess command output:'; | ||
|
r28901 | > echo "\$EVENT"; | ||
|
r40656 | > echo "\$MSG1") > $TESTTMP/command.log | ||
|
r39961 | > commandfinish=(echo 'logtoprocess commandfinish output:'; | ||
|
r28901 | > echo "\$EVENT"; | ||
> echo "\$MSG1"; | ||||
|
r40438 | > echo "canonical: \$OPT_CANONICAL_COMMAND") > $TESTTMP/commandfinish.log | ||
|
r39961 | > foo=(echo 'logtoprocess foo output:'; | ||
|
r28901 | > echo "\$EVENT"; | ||
|
r40656 | > echo "\$MSG1") > $TESTTMP/foo.log | ||
|
r28901 | > EOF | ||
Running a command triggers both a ui.log('command') and a | ||||
ui.log('commandfinish') call. The foo command also uses ui.log. | ||||
|
r30991 | Use sort to avoid ordering issues between the various processes we spawn: | ||
|
r40438 | $ hg fooba | ||
|
r40413 | $ sleep 1 | ||
|
r39961 | $ cat $TESTTMP/command.log | sort | ||
|
r28901 | |||
|
r39961 | command | ||
|
r40438 | fooba | ||
|
r39961 | logtoprocess command output: | ||
#if no-chg | ||||
$ cat $TESTTMP/commandfinish.log | sort | ||||
|
r40438 | canonical: foobar | ||
|
r28901 | commandfinish | ||
|
r40438 | fooba exited 0 after * seconds (glob) | ||
|
r39961 | logtoprocess commandfinish output: | ||
$ cat $TESTTMP/foo.log | sort | ||||
a message: spam | ||||
|
r28901 | foo | ||
logtoprocess foo output: | ||||
|
r39961 | #endif | ||
|
r30976 | |||
Confirm that logging blocked time catches stdio properly: | ||||
$ cp $HGRCPATH.bak $HGRCPATH | ||||
$ cat >> $HGRCPATH << EOF | ||||
> [extensions] | ||||
> logtoprocess= | ||||
> pager= | ||||
> [logtoprocess] | ||||
|
r39961 | > uiblocked=echo "\$EVENT stdio \$OPT_STDIO_BLOCKED ms command \$OPT_COMMAND_DURATION ms" > $TESTTMP/uiblocked.log | ||
|
r30976 | > [ui] | ||
> logblockedtimes=True | ||||
> EOF | ||||
|
r39961 | $ hg log | ||
|
r40413 | $ sleep 1 | ||
|
r39961 | $ cat $TESTTMP/uiblocked.log | ||
|
r30978 | uiblocked stdio [0-9]+.[0-9]* ms command [0-9]+.[0-9]* ms (re) | ||
|
r39961 | |||
Try to confirm that pager wait on logtoprocess: | ||||
|
r50387 | Add a script that waits on a file to appear. If the script is awaited by hg, | ||
the script will die after the timeout before we could touch the file and the | ||||
resulting file will not exist. If not, we will touch the file and see it. | ||||
|
r39961 | |||
|
r40908 | $ cat >> fakepager.py <<EOF | ||
> import sys | ||||
> printed = False | ||||
> for line in sys.stdin: | ||||
> sys.stdout.write(line) | ||||
> printed = True | ||||
> if not printed: | ||||
> sys.stdout.write('paged empty output!\n') | ||||
> EOF | ||||
|
r39961 | $ cat > $TESTTMP/wait-output.sh << EOF | ||
> #!/bin/sh | ||||
|
r50387 | > set -eu | ||
> "$RUNTESTDIR/testlib/wait-on-file" 10 "$TESTTMP/wait-for-touched" | ||||
> touch "$TESTTMP/touched" | ||||
|
r39961 | > EOF | ||
$ chmod +x $TESTTMP/wait-output.sh | ||||
$ cat >> $HGRCPATH << EOF | ||||
> [extensions] | ||||
> logtoprocess= | ||||
> pager= | ||||
|
r40908 | > [pager] | ||
> pager = "$PYTHON" $TESTTMP/fakepager.py | ||||
|
r39961 | > [logtoprocess] | ||
> commandfinish=$TESTTMP/wait-output.sh | ||||
> EOF | ||||
$ hg version -q --pager=always | ||||
Mercurial Distributed SCM (version *) (glob) | ||||
$ touch $TESTTMP/wait-for-touched | ||||
|
r50387 | $ "$RUNTESTDIR/testlib/wait-on-file" 5 "$TESTTMP/touched" | ||
|
r39961 | $ test -f $TESTTMP/touched && echo "SUCCESS Pager is not waiting on ltp" || echo "FAIL Pager is waiting on ltp" | ||
|
r39962 | SUCCESS Pager is not waiting on ltp | ||