t/t0211-trace2-perf.sh

   1 #!/bin/sh
   2
   3 test_description='test trace2 facility (perf target)'
   4
   5 TEST_PASSES_SANITIZE_LEAK=true
   6 . ./test-lib.sh
   7
   8 # Turn off any inherited trace2 settings for this test.
   9 sane_unset GIT_TRACE2 GIT_TRACE2_PERF GIT_TRACE2_EVENT
  10 sane_unset GIT_TRACE2_PERF_BRIEF
  11 sane_unset GIT_TRACE2_CONFIG_PARAMS
  12
  13 # Add t/helper directory to PATH so that we can use a relative
  14 # path to run nested instances of test-tool.exe (see 004child).
  15 # This helps with HEREDOC comparisons later.
  16 TTDIR="$GIT_BUILD_DIR/t/helper/" && export TTDIR
  17 PATH="$TTDIR:$PATH" && export PATH
  18
  19 # Warning: use of 'test_cmp' may run test-tool.exe and/or git.exe
  20 # Warning: to do the actual diff/comparison, so the HEREDOCs here
  21 # Warning: only cover our actual calls to test-tool and/or git.
  22 # Warning: So you may see extra lines in artifact files when
  23 # Warning: interactively debugging.
  24
  25 V=$(git version | sed -e 's/^git version //') && export V
  26
  27 # There are multiple trace2 targets: normal, perf, and event.
  28 # Trace2 events will/can be written to each active target (subject
  29 # to whatever filtering that target decides to do).
  30 # Test each target independently.
  31 #
  32 # Defer setting GIT_TRACE2_PERF until the actual command we want to
  33 # test because hidden git and test-tool commands in the test
  34 # harness can contaminate our output.
  35
  36 # Enable "brief" feature which turns off the prefix:
  37 #     "<clock> <file>:<line> | <nr_parents> | "
  38 GIT_TRACE2_PERF_BRIEF=1 && export GIT_TRACE2_PERF_BRIEF
  39
  40 # Repeat some of the t0210 tests using the perf target stream instead of
  41 # the normal stream.
  42 #
  43 # Tokens here of the form _FIELD_ have been replaced in the observed output.
  44
  45 # Verb 001return
  46 #
  47 # Implicit return from cmd_<verb> function propagates <code>.
  48
  49 test_expect_success 'perf stream, return code 0' '
  50         test_when_finished "rm trace.perf actual expect" &&
  51         GIT_TRACE2_PERF="$(pwd)/trace.perf" test-tool trace2 001return 0 &&
  52         perl "$TEST_DIRECTORY/t0211/scrub_perf.perl" <trace.perf >actual &&
  53         cat >expect <<-EOF &&
  54                 d0|main|version|||||$V
  55                 d0|main|start||_T_ABS_|||_EXE_ trace2 001return 0
  56                 d0|main|cmd_name|||||trace2 (trace2)
  57                 d0|main|exit||_T_ABS_|||code:0
  58                 d0|main|atexit||_T_ABS_|||code:0
  59         EOF
  60         test_cmp expect actual
  61 '
  62
  63 test_expect_success 'perf stream, return code 1' '
  64         test_when_finished "rm trace.perf actual expect" &&
  65         test_must_fail env GIT_TRACE2_PERF="$(pwd)/trace.perf" test-tool trace2 001return 1 &&
  66         perl "$TEST_DIRECTORY/t0211/scrub_perf.perl" <trace.perf >actual &&
  67         cat >expect <<-EOF &&
  68                 d0|main|version|||||$V
  69                 d0|main|start||_T_ABS_|||_EXE_ trace2 001return 1
  70                 d0|main|cmd_name|||||trace2 (trace2)
  71                 d0|main|exit||_T_ABS_|||code:1
  72                 d0|main|atexit||_T_ABS_|||code:1
  73         EOF
  74         test_cmp expect actual
  75 '
  76
  77 # Verb 003error
  78 #
  79 # To the above, add multiple 'error <msg>' events
  80
  81 test_expect_success 'perf stream, error event' '
  82         test_when_finished "rm trace.perf actual expect" &&
  83         GIT_TRACE2_PERF="$(pwd)/trace.perf" test-tool trace2 003error "hello world" "this is a test" &&
  84         perl "$TEST_DIRECTORY/t0211/scrub_perf.perl" <trace.perf >actual &&
  85         cat >expect <<-EOF &&
  86                 d0|main|version|||||$V
  87                 d0|main|start||_T_ABS_|||_EXE_ trace2 003error '\''hello world'\'' '\''this is a test'\''
  88                 d0|main|cmd_name|||||trace2 (trace2)
  89                 d0|main|error|||||hello world
  90                 d0|main|error|||||this is a test
  91                 d0|main|exit||_T_ABS_|||code:0
  92                 d0|main|atexit||_T_ABS_|||code:0
  93         EOF
  94         test_cmp expect actual
  95 '
  96
  97 # Verb 004child
  98 #
  99 # Test nested spawning of child processes.
 100 #
 101 # Conceptually, this looks like:
 102 #    P1: TT trace2 004child
 103 #    P2: |--- TT trace2 004child
 104 #    P3:      |--- TT trace2 001return 0
 105 #
 106 # Which should generate events:
 107 #    P1: version
 108 #    P1: start
 109 #    P1: cmd_name
 110 #    P1: child_start
 111 #        P2: version
 112 #        P2: start
 113 #        P2: cmd_name
 114 #        P2: child_start
 115 #            P3: version
 116 #            P3: start
 117 #            P3: cmd_name
 118 #            P3: exit
 119 #            P3: atexit
 120 #        P2: child_exit
 121 #        P2: exit
 122 #        P2: atexit
 123 #    P1: child_exit
 124 #    P1: exit
 125 #    P1: atexit
 126
 127 test_expect_success 'perf stream, child processes' '
 128         test_when_finished "rm trace.perf actual expect" &&
 129         GIT_TRACE2_PERF="$(pwd)/trace.perf" test-tool trace2 004child test-tool trace2 004child test-tool trace2 001return 0 &&
 130         perl "$TEST_DIRECTORY/t0211/scrub_perf.perl" <trace.perf >actual &&
 131         cat >expect <<-EOF &&
 132                 d0|main|version|||||$V
 133                 d0|main|start||_T_ABS_|||_EXE_ trace2 004child test-tool trace2 004child test-tool trace2 001return 0
 134                 d0|main|cmd_name|||||trace2 (trace2)
 135                 d0|main|child_start||_T_ABS_|||[ch0] class:? argv:[test-tool trace2 004child test-tool trace2 001return 0]
 136                 d1|main|version|||||$V
 137                 d1|main|start||_T_ABS_|||_EXE_ trace2 004child test-tool trace2 001return 0
 138                 d1|main|cmd_name|||||trace2 (trace2/trace2)
 139                 d1|main|child_start||_T_ABS_|||[ch0] class:? argv:[test-tool trace2 001return 0]
 140                 d2|main|version|||||$V
 141                 d2|main|start||_T_ABS_|||_EXE_ trace2 001return 0
 142                 d2|main|cmd_name|||||trace2 (trace2/trace2/trace2)
 143                 d2|main|exit||_T_ABS_|||code:0
 144                 d2|main|atexit||_T_ABS_|||code:0
 145                 d1|main|child_exit||_T_ABS_|_T_REL_||[ch0] pid:_PID_ code:0
 146                 d1|main|exit||_T_ABS_|||code:0
 147                 d1|main|atexit||_T_ABS_|||code:0
 148                 d0|main|child_exit||_T_ABS_|_T_REL_||[ch0] pid:_PID_ code:0
 149                 d0|main|exit||_T_ABS_|||code:0
 150                 d0|main|atexit||_T_ABS_|||code:0
 151         EOF
 152         test_cmp expect actual
 153 '
 154
 155 sane_unset GIT_TRACE2_PERF_BRIEF
 156
 157 # Now test without environment variables and get all Trace2 settings
 158 # from the global config.
 159
 160 test_expect_success 'using global config, perf stream, return code 0' '
 161         test_when_finished "rm trace.perf actual expect" &&
 162         test_config_global trace2.perfBrief 1 &&
 163         test_config_global trace2.perfTarget "$(pwd)/trace.perf" &&
 164         test-tool trace2 001return 0 &&
 165         perl "$TEST_DIRECTORY/t0211/scrub_perf.perl" <trace.perf >actual &&
 166         cat >expect <<-EOF &&
 167                 d0|main|version|||||$V
 168                 d0|main|start||_T_ABS_|||_EXE_ trace2 001return 0
 169                 d0|main|cmd_name|||||trace2 (trace2)
 170                 d0|main|exit||_T_ABS_|||code:0
 171                 d0|main|atexit||_T_ABS_|||code:0
 172         EOF
 173         test_cmp expect actual
 174 '
 175
 176 test_done