diff options
| author | Paul Buetow <paul@buetow.org> | 2021-11-07 13:27:47 +0200 |
|---|---|---|
| committer | Paul Buetow <paul@buetow.org> | 2021-11-07 13:27:47 +0200 |
| commit | cff54875e836a5da0fd3521bea23817f2b40ec54 (patch) | |
| tree | 18d68be29d2f612cb86262e7339aabbf916dc67f /doc | |
| parent | e11a4b5bc7960ee7f20cabc7fd7810572d934806 (diff) | |
add int test gif
Diffstat (limited to 'doc')
| -rw-r--r-- | doc/asciinema/testing.rec | 348 | ||||
| -rw-r--r-- | doc/testing.gif | bin | 0 -> 2637253 bytes | |||
| -rw-r--r-- | doc/testing.md | 2 |
3 files changed, 350 insertions, 0 deletions
diff --git a/doc/asciinema/testing.rec b/doc/asciinema/testing.rec new file mode 100644 index 0000000..a56b91f --- /dev/null +++ b/doc/asciinema/testing.rec @@ -0,0 +1,348 @@ +{"version": 2, "width": 80, "height": 24, "timestamp": 1636284228, "env": {"SHELL": "/bin/bash", "TERM": "xterm-256color"}} +[0.168437, "o", "\u001b[1m\u001b[7m%\u001b[27m\u001b[1m\u001b[0m \r \r"] +[0.169152, "o", "\u001b]7;file://earth/home/paul\u001b\\"] +[0.186317, "o", "\r\u001b[0m\u001b[27m\u001b[24m\u001b[J\r\n\u001b[1;33mpaul\u001b[0m in \u001b[1;2;32mearth\u001b[0m in \u001b[1;36m~\u001b[0m via \u001b[1;36m🏎💨 \u001b[0m\u001b[1;36mv1.16.8\u001b[0m\u001b[1;36m \u001b[0m\r\n\u001b[1;32m❯\u001b[0m \u001b[K\u001b[?2004h"] +[0.772191, "o", "c"] +[0.850476, "o", "\bcd"] +[0.961111, "o", " "] +[1.067221, "o", "d"] +[1.296534, "o", "t"] +[1.514029, "o", "a"] +[1.616685, "o", "i"] +[1.884458, "o", "l"] +[2.681723, "o", "\u001b[?2004l\r\r\n"] +[2.682696, "o", "~/git/dtail\r\n\u001b[1m\u001b[7m%\u001b[27m\u001b[1m\u001b[0m \r \r"] +[2.684712, "o", "\u001b]7;file://earth/home/paul/git/dtail\u001b\\"] +[2.717958, "o", "\r\u001b[0m\u001b[27m\u001b[24m\u001b[J\r\n\u001b[1;33mpaul\u001b[0m in \u001b[1;2;32mearth\u001b[0m in \u001b[1;36mdtail\u001b[0m on \u001b[1;35m🌱 \u001b[0m\u001b[1;35m4.0.0-RC\u001b[0m \u001b[1;31m[\u001b[0m\u001b[1;31m?\u001b[0m\u001b[1;31m]\u001b[0m via \u001b[1;36m🏎💨 \u001b[0m\u001b[1;36mv1.16.8\u001b[0m\u001b[1;36m \u001b[0m\r\n\u001b[1;32m❯\u001b[0m \u001b[K\u001b[?2004h"] +[4.623974, "o", "\u001b[7mexport DTAIL_INTEGRATION_TEST_RUN_MODE=yes\u001b[27m"] +[5.620183, "o", "\u001b[42D\u001b[27me\u001b[27mx\u001b[27mp\u001b[27mo\u001b[27mr\u001b[27mt\u001b[27m \u001b[27mD\u001b[27mT\u001b[27mA\u001b[27mI\u001b[27mL\u001b[27m_\u001b[27mI\u001b[27mN\u001b[27mT\u001b[27mE\u001b[27mG\u001b[27mR\u001b[27mA\u001b[27mT\u001b[27mI\u001b[27mO\u001b[27mN\u001b[27m_\u001b[27mT\u001b[27mE\u001b[27mS\u001b[27mT\u001b[27m_\u001b[27mR\u001b[27mU\u001b[27mN\u001b[27m_\u001b[27mM\u001b[27mO\u001b[27mD\u001b[27mE\u001b[27m=\u001b[27my\u001b[27me\u001b[27ms"] +[6.287299, "o", "\u001b[?2004l\r\r\n"] +[6.288336, "o", "\u001b[1m\u001b[7m%\u001b[27m\u001b[1m\u001b[0m \r \r"] +[6.290235, "o", "\u001b]7;file://earth/home/paul/git/dtail\u001b\\"] +[6.313466, "o", "\r\u001b[0m\u001b[27m\u001b[24m\u001b[J\r\n\u001b[1;33mpaul\u001b[0m in \u001b[1;2;32mearth\u001b[0m in \u001b[1;36mdtail\u001b[0m on \u001b[1;35m🌱 \u001b[0m\u001b[1;35m4.0.0-RC\u001b[0m \u001b[1;31m[\u001b[0m\u001b[1;31m?\u001b[0m\u001b[1;31m]\u001b[0m via \u001b[1;36m🏎💨 \u001b[0m\u001b[1;36mv1.16.8\u001b[0m\u001b[1;36m \u001b[0m\r\n\u001b[1;32m❯\u001b[0m \u001b[K\u001b[?2004h"] +[6.848094, "o", "m"] +[6.900012, "o", "\bma"] +[7.019422, "o", "k"] +[7.087074, "o", "e"] +[7.21384, "o", " "] +[7.35933, "o", "c"] +[7.464946, "o", "l"] +[7.571172, "o", "e"] +[7.770994, "o", "a"] +[7.953558, "o", "n"] +[8.88548, "o", "\u001b[?2004l\r\r\n"] +[8.891286, "o", "ls ./cmd/ | while read cmd; do \\\r\n test -f $cmd && rm $cmd; \\\r\ndone\r\n"] +[8.923103, "o", "\u001b[1m\u001b[7m%\u001b[27m\u001b[1m\u001b[0m \r \r"] +[8.924764, "o", "\u001b]7;file://earth/home/paul/git/dtail\u001b\\"] +[8.948887, "o", "\r\u001b[0m\u001b[27m\u001b[24m\u001b[J\r\n\u001b[1;33mpaul\u001b[0m in \u001b[1;2;32mearth\u001b[0m in \u001b[1;36mdtail\u001b[0m on \u001b[1;35m🌱 \u001b[0m\u001b[1;35m4.0.0-RC\u001b[0m \u001b[1;31m[\u001b[0m\u001b[1;31m?\u001b[0m\u001b[1;31m]\u001b[0m via \u001b[1;36m🏎💨 \u001b[0m\u001b[1;36mv1.16.8\u001b[0m\u001b[1;36m \u001b[0m\r\n\u001b[1;32m❯\u001b[0m \u001b[K\u001b[?2004h"] +[9.692142, "o", "m\bma"] +[9.835245, "o", "k"] +[9.913144, "o", "e"] +[11.058078, "o", "\u001b[?2004l\r\r\n"] +[11.064178, "o", "go build -o dserver ./cmd/dserver/main.go\r\n"] +[12.396627, "o", "go build -o dcat ./cmd/dcat/main.go\r\n"] +[13.691784, "o", "go build -o dgrep ./cmd/dgrep/main.go\r\n"] +[15.035427, "o", "go build -o dmap ./cmd/dmap/main.go\r\n"] +[16.233035, "o", "go build -o dtail ./cmd/dtail/main.go\r\n"] +[17.480751, "o", "go build -o dtailhealth ./cmd/dtailhealth/main.go\r\n"] +[18.740255, "o", "\u001b[1m\u001b[7m%\u001b[27m\u001b[1m\u001b[0m \r \r"] +[18.741339, "o", "\u001b]7;file://earth/home/paul/git/dtail\u001b\\"] +[18.757109, "o", "\r\u001b[0m\u001b[27m\u001b[24m\u001b[J\r\n\u001b[1;33mpaul\u001b[0m in \u001b[1;2;32mearth\u001b[0m in \u001b[1;36mdtail\u001b[0m on \u001b[1;35m🌱 \u001b[0m\u001b[1;35m4.0.0-RC\u001b[0m \u001b[1;31m[\u001b[0m\u001b[1;31m?\u001b[0m\u001b[1;31m]\u001b[0m via \u001b[1;36m🏎💨 \u001b[0m\u001b[1;36mv1.16.8\u001b[0m\u001b[1;36m \u001b[0mcmdruntime \r\n\u001b[1;32m❯\u001b[0m \u001b[K\u001b[?2004h"] +[19.39628, "o", "m"] +[19.455472, "o", "\bma"] +[19.586245, "o", "k"] +[19.66266, "o", "e"] +[19.774101, "o", " "] +[19.865364, "o", "t"] +[19.91748, "o", "e"] +[20.169476, "o", "s"] +[20.401606, "o", "t"] +[20.734477, "o", "\u001b[?2004l\r\r\n"] +[20.740095, "o", "go clean -testcache\r\n"] +[20.746166, "o", "set -e; find . -name '*_test.go' | while read file; do dirname $file; done | \\\r\n\tsort -u | while read dir; do go test --race -v $dir || exit 2; done\r\n"] +[21.121796, "o", "=== RUN TestDCat1\r\n"] +[21.122077, "o", " commandutils.go:23: Creating stdout file dcat1.out\r\n"] +[21.122307, "o", " commandutils.go:30: Running command ../dcat --plain --cfg none dcat1a.txt\r\n"] +[21.159068, "o", " commandutils.go:33: Done running command! <nil>\r\n"] +[21.15924, "o", " fileutils.go:77: Comparing files dcat1.out dcat1a.txt\r\n"] +[21.15941, "o", " fileutils.go:118: SHA dcat1.out cH9smTMt9KMpDp_jq6PpAM6i7UV4abAI0Ic5mtyi0Es=\r\n"] +[21.159628, "o", " fileutils.go:118: SHA dcat1a.txt cH9smTMt9KMpDp_jq6PpAM6i7UV4abAI0Ic5mtyi0Es=\r\n"] +[21.159747, "o", " commandutils.go:23: Creating stdout file dcat1.out\r\n"] +[21.159832, "o", " commandutils.go:30: Running command ../dcat --plain --cfg none dcat1b.txt\r\n"] +[21.174154, "o", " commandutils.go:33: Done running command! <nil>\r\n"] +[21.17426, "o", " fileutils.go:77: Comparing files dcat1.out dcat1b.txt\r\n"] +[21.17434, "o", " fileutils.go:118: SHA dcat1.out 0_4MC9xrPZoOIaEbpV_oN8DBdcYRQw2d4E0G5D_PR0c=\r\n"] +[21.174536, "o", " fileutils.go:118: SHA dcat1b.txt 0_4MC9xrPZoOIaEbpV_oN8DBdcYRQw2d4E0G5D_PR0c=\r\n commandutils.go:23: Creating stdout file dcat1.out\r\n"] +[21.174601, "o", " commandutils.go:30: Running command ../dcat --plain --cfg none dcat1c.txt\r\n"] +[21.188499, "o", " commandutils.go:33: Done running command! <nil>\r\n"] +[21.188645, "o", " fileutils.go:77: Comparing files dcat1.out dcat1c.txt\r\n"] +[21.188722, "o", " fileutils.go:118: SHA dcat1.out 8muYdoE22xK0I2jB4RHhUaN2T32CSzl6o32ar6o693A=\r\n"] +[21.188797, "o", " fileutils.go:118: SHA dcat1c.txt 8muYdoE22xK0I2jB4RHhUaN2T32CSzl6o32ar6o693A=\r\n"] +[21.188999, "o", " commandutils.go:23: Creating stdout file dcat1.out\r\n"] +[21.189115, "o", " commandutils.go:30: Running command ../dcat --plain --cfg none dcat1d.txt\r\n"] +[21.193466, "o", " commandutils.go:33: Done running command! <nil>\r\n"] +[21.193574, "o", " fileutils.go:77: Comparing files dcat1.out dcat1d.txt\r\n"] +[21.193753, "o", " fileutils.go:118: SHA dcat1.out jXofpjLptyjKEtqcKBEe-YLjW8doKKHqaYHBJb3NmP8=\r\n fileutils.go:118: SHA dcat1d.txt jXofpjLptyjKEtqcKBEe-YLjW8doKKHqaYHBJb3NmP8=\r\n"] +[21.193816, "o", "--- PASS: TestDCat1 (0.07s)\r\n"] +[21.193922, "o", "=== RUN TestDCat2\r\n"] +[21.194179, "o", " commandutils.go:23: Creating stdout file dcat2.out\r\n"] +[21.194377, "o", " commandutils.go:30: Running command ../dcat --plain --logLevel error --cfg none dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt "] +[21.194401, "o", "dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt dcat2.txt\r\n"] +[23.755537, "o", " commandutils.go:33: Done running command! <nil>\r\n"] +[23.757611, "o", " fileutils.go:16: Mapping dcat2.out\r\n"] +[23.795344, "o", " fileutils.go:16: Mapping dcat2.txt.expected\r\n"] +[23.833555, "o", " fileutils.go:62: Checking whether dcat2.out has same lines as file dcat2.txt.expected (ignoring line order)\r\n"] +[23.833817, "o", " fileutils.go:67: Checking whether dcat2.txt.expected has same lines as file dcat2.out (ignoring line order)\r\n"] +[23.834421, "o", "--- PASS: TestDCat2 (2.64s)\r\n=== RUN TestDCat3\r\n"] +[23.834647, "o", " commandutils.go:23: Creating stdout file dcat3.out\r\n"] +[23.834763, "o", " commandutils.go:30: Running command ../dcat --plain --logLevel error --cfg none dcat3.txt\r\n"] +[23.849713, "o", " commandutils.go:33: Done running command! <nil>\r\n fileutils.go:16: Mapping dcat3.out\r\n"] +[23.849945, "o", " fileutils.go:16: Mapping dcat3.txt.expected\r\n fileutils.go:62: Checking whether dcat3.out has same lines as file dcat3.txt.expected (ignoring line order)\r\n"] +[23.85003, "o", " fileutils.go:67: Checking whether dcat3.txt.expected has same lines as file dcat3.out (ignoring line order)\r\n"] +[23.850191, "o", "--- PASS: TestDCat3 (0.02s)\r\n=== RUN TestDCatColors\r\n"] +[23.850397, "o", " commandutils.go:23: Creating stdout file dcatcolors.out\r\n"] +[23.850493, "o", " commandutils.go:30: Running command ../dcat --logLevel error --cfg none dcatcolors.txt\r\n"] +[24.417653, "o", " commandutils.go:33: Done running command! <nil>\r\n"] +[24.419069, "o", " fileutils.go:77: Comparing files dcatcolors.out dcatcolors.expected\r\n"] +[24.427391, "o", " fileutils.go:118: SHA dcatcolors.out 14rQOSy0oG5x26oIHItq62Tmh18XVGwWo5Cf5f9CC-I=\r\n"] +[24.435069, "o", " fileutils.go:118: SHA dcatcolors.expected 14rQOSy0oG5x26oIHItq62Tmh18XVGwWo5Cf5f9CC-I=\r\n"] +[24.435244, "o", "--- PASS: TestDCatColors (0.58s)\r\n"] +[24.435326, "o", "=== RUN TestDGrep1\r\n"] +[24.435453, "o", " commandutils.go:23: Creating stdout file dgrep.stdout.tmp\r\n"] +[24.435559, "o", " commandutils.go:30: Running command ../dgrep --plain --cfg none --grep 20211002-071947 mapr_testdata.log\r\n"] +[24.451286, "o", " commandutils.go:33: Done running command! <nil>\r\n fileutils.go:77: Comparing files dgrep.stdout.tmp dgrep1.txt.expected\r\n"] +[24.451463, "o", " fileutils.go:118: SHA dgrep.stdout.tmp G9TGtR91Xvp1dBVVEQkKdgx5iCFB32AEGVWdjwlLpAw=\r\n fileutils.go:118: SHA dgrep1.txt.expected G9TGtR91Xvp1dBVVEQkKdgx5iCFB32AEGVWdjwlLpAw=\r\n"] +[24.451568, "o", "--- PASS: TestDGrep1 (0.02s)\r\n"] +[24.45163, "o", "=== RUN TestDGrep2\r\n"] +[24.451753, "o", " commandutils.go:23: Creating stdout file dgrep2.stdout.tmp\r\n"] +[24.451912, "o", " commandutils.go:30: Running command ../dgrep --plain --cfg none --grep 20211002-071947 --invert mapr_testdata.log\r\n"] +[24.548226, "o", " commandutils.go:33: Done running command! <nil>\r\n"] +[24.548395, "o", " fileutils.go:77: Comparing files dgrep2.stdout.tmp dgrep2.txt.expected\r\n"] +[24.548944, "o", " fileutils.go:118: SHA dgrep2.stdout.tmp XdaqtbhnLomR4BmeazaLhFcFXDcRcvu4a8d6784qb4Y=\r\n"] +[24.549534, "o", " fileutils.go:118: SHA dgrep2.txt.expected XdaqtbhnLomR4BmeazaLhFcFXDcRcvu4a8d6784qb4Y=\r\n"] +[24.549585, "o", "--- PASS: TestDGrep2 (0.10s)\r\n"] +[24.549653, "o", "=== RUN TestDGrepContext1\r\n"] +[24.549739, "o", " commandutils.go:23: Creating stdout file dgrepcontext1.stdout.tmp\r\n"] +[24.549833, "o", " commandutils.go:30: Running command ../dgrep --plain --cfg none --grep 20211002-071947 --after 3 --before 3 mapr_testdata.log\r\n"] +[24.564484, "o", " commandutils.go:33: Done running command! <nil>\r\n"] +[24.564598, "o", " fileutils.go:77: Comparing files dgrepcontext1.stdout.tmp dgrepcontext1.txt.expected\r\n"] +[24.564688, "o", " fileutils.go:118: SHA dgrepcontext1.stdout.tmp _iEuvd1ycfVT7rh_RQi8_MDretdivpHrAS6LttNWXiQ=\r\n"] +[24.564772, "o", " fileutils.go:118: SHA dgrepcontext1.txt.expected _iEuvd1ycfVT7rh_RQi8_MDretdivpHrAS6LttNWXiQ=\r\n"] +[24.564905, "o", "--- PASS: TestDGrepContext1 (0.02s)\r\n"] +[24.564999, "o", "=== RUN TestDGrepContext2\r\n"] +[24.565137, "o", " commandutils.go:23: Creating stdout file dgrepcontext2.stdout.tmp\r\n"] +[24.565259, "o", " commandutils.go:30: Running command ../dgrep --plain --cfg none --grep 20211002 --max 3 mapr_testdata.log\r\n"] +[24.579018, "o", " commandutils.go:33: Done running command! <nil>\r\n"] +[24.579065, "o", " fileutils.go:77: Comparing files dgrepcontext2.stdout.tmp dgrepcontext2.txt.expected\r\n"] +[24.57919, "o", " fileutils.go:118: SHA dgrepcontext2.stdout.tmp HU8misgS5jBPmLWySPAH0BTZ7jsRjqLzxQ0_BHN_EI0=\r\n"] +[24.579242, "o", " fileutils.go:118: SHA dgrepcontext2.txt.expected HU8misgS5jBPmLWySPAH0BTZ7jsRjqLzxQ0_BHN_EI0=\r\n"] +[24.579345, "o", "--- PASS: TestDGrepContext2 (0.01s)\r\n"] +[24.579402, "o", "=== RUN TestDMap1\r\n"] +[24.579544, "o", " dmap_test.go:18: Testing dmap with input file\r\n"] +[24.579633, "o", " commandutils.go:62: ../dmap --cfg none --query from STATS select count($line),last($time),avg($goroutines),min(concurrentConnections),max(lifetimeConnections) group by $hostname outfile dmap1.csv.tmp --logger stdout --logLevel error --noColor mapr_testdata.log\r\n"] +[24.692473, "o", " commandutils.go:138: Command finished with with exit code 0: <nil>\r\n fileutils.go:77: Comparing files dmap1.csv.tmp dmap1.csv.expected\r\n"] +[24.692645, "o", " fileutils.go:118: SHA dmap1.csv.tmp DV1HWO0ALVJlIIA_fbabPA0lzCcv9lSPi5giQ2d0wd0=\r\n"] +[24.692685, "o", " fileutils.go:118: SHA dmap1.csv.expected DV1HWO0ALVJlIIA_fbabPA0lzCcv9lSPi5giQ2d0wd0=\r\n fileutils.go:77: Comparing files dmap1.csv.tmp.query dmap1.csv.query.expected\r\n"] +[24.692811, "o", " fileutils.go:118: SHA dmap1.csv.tmp.query FB07BsLwYwddmQ6c0wYHp4npOMH0gLpkgx875n0NL74=\r\n"] +[24.692947, "o", " fileutils.go:118: SHA dmap1.csv.query.expected FB07BsLwYwddmQ6c0wYHp4npOMH0gLpkgx875n0NL74=\r\n"] +[24.693056, "o", " dmap_test.go:23: Testing dmap with stdin input pipe\r\n"] +[24.693132, "o", " commandutils.go:62: ../dmap --cfg none --query from STATS select count($line),last($time),avg($goroutines),min(concurrentConnections),max(lifetimeConnections) group by $hostname outfile dmap1.csv.tmp --logger stdout --logLevel error --noColor\r\n"] +[24.693547, "o", " commandutils.go:88: Piping mapr_testdata.log to stdin pipe\r\n"] +[24.805558, "o", " commandutils.go:138: Command finished with with exit code 0: <nil>\r\n fileutils.go:77: Comparing files dmap1.csv.tmp dmap1.csv.expected\r\n"] +[24.805732, "o", " fileutils.go:118: SHA dmap1.csv.tmp DV1HWO0ALVJlIIA_fbabPA0lzCcv9lSPi5giQ2d0wd0=\r\n fileutils.go:118: SHA dmap1.csv.expected DV1HWO0ALVJlIIA_fbabPA0lzCcv9lSPi5giQ2d0wd0=\r\n fileutils.go:77: Comparing files dmap1.csv.tmp.query dmap1.csv.query.expected\r\n"] +[24.805829, "o", " fileutils.go:118: SHA dmap1.csv.tmp.query FB07BsLwYwddmQ6c0wYHp4npOMH0gLpkgx875n0NL74=\r\n"] +[24.805938, "o", " fileutils.go:118: SHA dmap1.csv.query.expected FB07BsLwYwddmQ6c0wYHp4npOMH0gLpkgx875n0NL74=\r\n"] +[24.806058, "o", "--- PASS: TestDMap1 (0.23s)\r\n"] +[24.80612, "o", "=== RUN TestDMap2\r\n"] +[24.806293, "o", " commandutils.go:23: Creating stdout file dmap2.stdout.tmp\r\n"] +[24.806394, "o", " commandutils.go:30: Running command ../dmap --query from STATS select count($time),$time,max($goroutines),avg($goroutines),min($goroutines) group by $time order by count($time) outfile dmap2.csv.tmp --cfg none mapr_testdata.log\r\n"] +[24.935667, "o", " commandutils.go:33: Done running command! <nil>\r\n fileutils.go:16: Mapping dmap2.csv.tmp\r\n"] +[24.936123, "o", " fileutils.go:16: Mapping dmap2.csv.expected\r\n"] +[24.936494, "o", " fileutils.go:62: Checking whether dmap2.csv.tmp has same lines as file dmap2.csv.expected (ignoring line order)\r\n"] +[24.936655, "o", " fileutils.go:67: Checking whether dmap2.csv.expected has same lines as file dmap2.csv.tmp (ignoring line order)\r\n fileutils.go:77: Comparing files dmap2.csv.tmp.query dmap2.csv.query.expected\r\n"] +[24.936769, "o", " fileutils.go:118: SHA dmap2.csv.tmp.query Ur-BBAysH4YrcVNjO97ZO5FltvUgn3O2Yqsf1_j988w=\r\n"] +[24.937082, "o", " fileutils.go:118: SHA dmap2.csv.query.expected Ur-BBAysH4YrcVNjO97ZO5FltvUgn3O2Yqsf1_j988w=\r\n--- PASS: TestDMap2 (0.13s)\r\n=== RUN TestDMap3\r\n"] +[24.937248, "o", " commandutils.go:62: ../dmap --query from STATS select count($time),$time,max($goroutines),avg($goroutines),min($goroutines) group by $time order by count($time) outfile dmap3.csv.tmp --cfg none --logger stdout --logLevel info --noColor mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testd"] +[24.93731, "o", "ata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log mapr_testdata.log\r\n"] +[36.101783, "o", " commandutils.go:138: Command finished with with exit code 0: <nil>\r\n fileutils.go:16: Mapping dmap3.csv.tmp\r\n"] +[36.102453, "o", " fileutils.go:16: Mapping dmap3.csv.expected\r\n"] +[36.102998, "o", " fileutils.go:62: Checking whether dmap3.csv.tmp has same lines as file dmap3.csv.expected (ignoring line order)\r\n"] +[36.103433, "o", " fileutils.go:67: Checking whether dmap3.csv.expected has same lines as file dmap3.csv.tmp (ignoring line order)\r\n fileutils.go:77: Comparing files dmap3.csv.tmp.query dmap3.csv.query.expected\r\n fileutils.go:118: SHA dmap3.csv.tmp.query _tbPMkVDoPmaBF8gE2JM9o4y0flxfiCJ5L6sV1OU3SM=\r\n"] +[36.103637, "o", " fileutils.go:118: SHA dmap3.csv.query.expected _tbPMkVDoPmaBF8gE2JM9o4y0flxfiCJ5L6sV1OU3SM=\r\n"] +[36.103781, "o", "--- PASS: TestDMap3 (11.17s)\r\n"] +[36.103912, "o", "=== RUN TestDServer1\r\n"] +[36.10422, "o", " commandutils.go:62: ../dserver --cfg dserver1.cfg --logger stdout --logLevel info --bindAddress localhost --shutdownAfter 5 --port 4242\r\n"] +[36.109892, "o", " commandutils.go:131: DTail 4.0.0-RC5 Protocol 4.1 Have a lot of fun!\r\n"] +[36.110386, "o", " commandutils.go:131: INFO|20211107-132424|Starting server|DTail 4.0.0-RC5 Protocol 4.1 Have a lot of fun!\r\n"] +[36.110494, "o", " commandutils.go:131: INFO|20211107-132424|Reading private server RSA host key from file|./ssh_host_key\r\n"] +[36.110836, "o", " commandutils.go:131: INFO|20211107-132424|Starting server\r\n"] +[36.111015, "o", " commandutils.go:131: INFO|20211107-132424|Binding server|localhost:4242\r\n"] +[36.112372, "o", " commandutils.go:131: INFO|20211107-132424|Starting scheduled job runner after 2s\r\n"] +[36.112495, "o", " commandutils.go:131: INFO|20211107-132424|Starting continuous job runner after 2s\r\n"] +[38.114251, "o", " commandutils.go:131: INFO|20211107-132426|Starting job dserver_schedule_test\r\n"] +[38.115407, "o", " commandutils.go:131: INFO|20211107-132426|Handling connection\r\n"] +[38.139708, "o", " commandutils.go:131: INFO|20211107-132426|231420|stats.go:53|8|20|13|0.36|40h54m32s|MAPREDUCE:STATS|currentConnections=1|lifetimeConnections=1\r\n"] +[38.139934, "o", " commandutils.go:131: INFO|20211107-132426|DTAIL-SCHEDULE@127.0.0.1:54788|Invoking channel handler\r\n commandutils.go:131: INFO|20211107-132426|DTAIL-SCHEDULE@127.0.0.1:54788|Invoking request handler\r\n"] +[38.140969, "o", " commandutils.go:131: INFO|20211107-132426|Creating log format parser|default\r\n"] +[38.141339, "o", " commandutils.go:131: INFO|20211107-132426|DTAIL-SCHEDULE@127.0.0.1:54788|Start reading|mapr_testdata.log|mapr_testdata.log\r\n"] +[38.250546, "o", " commandutils.go:131: INFO|20211107-132426|mapr_testdata.log|End of file reached\r\n"] +[38.25318, "o", " commandutils.go:131: INFO|20211107-132426|Serializing mapreduce result\r\n"] +[38.253584, "o", " commandutils.go:131: ERROR|20211107-132426|DTAIL-SCHEDULE@127.0.0.1:54788|read tcp 127.0.0.1:4242->127.0.0.1:54788: use of closed network connection\r\n"] +[38.253661, "o", " commandutils.go:131: INFO|20211107-132426|Writing outfile|./dserver1.csv\r\n commandutils.go:131: INFO|20211107-132426|231420|stats.go:53|8|14|13|0.36|40h54m32s|MAPREDUCE:STATS|currentConnections=0|lifetimeConnections=1\r\n"] +[38.253717, "o", " commandutils.go:131: INFO|20211107-132426|DTAIL-SCHEDULE@127.0.0.1:54788|Good bye Mister!\r\n"] +[38.253867, "o", " commandutils.go:131: INFO|20211107-132426|Job exited with status 0\r\n"] +[41.113188, "o", " commandutils.go:138: Command finished with with exit code 0: <nil>\r\n fileutils.go:77: Comparing files dserver1.csv dserver1.csv.expected\r\n"] +[41.1134, "o", " fileutils.go:118: SHA dserver1.csv DV1HWO0ALVJlIIA_fbabPA0lzCcv9lSPi5giQ2d0wd0=\r\n"] +[41.113553, "o", " fileutils.go:118: SHA dserver1.csv.expected DV1HWO0ALVJlIIA_fbabPA0lzCcv9lSPi5giQ2d0wd0=\r\n"] +[41.113615, "o", " fileutils.go:77: Comparing files dserver1.csv.query dserver1.csv.query.expected\r\n"] +[41.113807, "o", " fileutils.go:118: SHA dserver1.csv.query VUNGjx2XBLJZo3kt525nAEEZ1aM7LFNIAHW4v8GfFSc=\r\n"] +[41.114001, "o", " fileutils.go:118: SHA dserver1.csv.query.expected VUNGjx2XBLJZo3kt525nAEEZ1aM7LFNIAHW4v8GfFSc=\r\n"] +[41.114272, "o", "--- PASS: TestDServer1 (5.01s)\r\n"] +[41.114399, "o", "=== RUN TestDServer2\r\n"] +[41.114923, "o", " commandutils.go:62: ../dserver --cfg dserver2.cfg --logger stdout --logLevel debug --bindAddress localhost --shutdownAfter 7 --port 4243\r\n"] +[41.120362, "o", " commandutils.go:131: DTail 4.0.0-RC5 Protocol 4.1 Have a lot of fun!\r\n"] +[41.120822, "o", " commandutils.go:131: INFO|20211107-132429|Starting server|DTail 4.0.0-RC5 Protocol 4.1 Have a lot of fun!\r\n"] +[41.120992, "o", " commandutils.go:131: INFO|20211107-132429|Reading private server RSA host key from file|./ssh_host_key\r\n"] +[41.121233, "o", " commandutils.go:131: INFO|20211107-132429|Starting server\r\n"] +[41.121364, "o", " commandutils.go:131: INFO|20211107-132429|Binding server|localhost:4243\r\n"] +[41.122678, "o", " commandutils.go:131: DEBUG|20211107-132429|Starting listener loop\r\n"] +[41.122827, "o", " commandutils.go:131: INFO|20211107-132429|Starting scheduled job runner after 2s\r\n commandutils.go:131: INFO|20211107-132429|Starting continuous job runner after 2s\r\n"] +[43.124446, "o", " commandutils.go:131: DEBUG|20211107-132431|dserver_continuous_test|Processing job\r\n commandutils.go:131: DEBUG|20211107-132431|Cumulative mapreduce mode?|false\r\n"] +[43.124774, "o", " commandutils.go:131: DEBUG|20211107-132431|Initiating base client|Args(Arguments:[],ConfigFile:,ConnectionsPerCPU:10,Discovery:,LogDir:,LogLevel:,Logger:,Mode:tail,NoColor:false,QueryStr:from INTEGRATIONTEST select last($line),max(foo),min(bar) group by $hostname interval 1 outfile ./dserver2.csv,Quiet:false,RegexInvert:false,RegexStr:\\|MAPREDUCE:INTEGRATIONTEST\\|,SSHAuthMethods:[0x756460],SSHBindAddress:,SSHHostKeyCallback:<nil>,SSHPrivateKeyFilePath:,SSHPort:0,Serverless:false,ServersStr:localhost,Plain:false,Timeout:0,TrustAllHosts:false,UserName:DTAIL-CONTINUOUS,What:./dserver2.log)\r\n commandutils.go:131: DEBUG|20211107-132431|Retrieving server list from comma separated list|localhost\r\n commandutils.go:131: DEBUG|20211107-132431|Deduped server list|1|1\r\n"] +[43.12483, "o", " commandutils.go:131: DEBUG|20211107-132431|Shuffling server list\r\n"] +[43.125115, "o", " commandutils.go:131: DEBUG|20211107-132431|Discovered servers|1|[localhost]\r\n commandutils.go:131: DEBUG|20211107-132431|localhost|Creating new connection|localhost|baseHandler(Done(no),server:localhost,shellStarted:false,status:-1)@0xc0002b4150|[map from INTEGRATIONTEST select last($line),max(foo),min(bar) group by $hostname interval 1 outfile ./dserver2.csv tail: ./dserver2.log regex:default \\|MAPREDUCE:INTEGRATIONTEST\\|]\r\n"] +[43.12521, "o", " commandutils.go:131: INFO|20211107-132431|Starting job dserver_continuous_test\r\n"] +[43.125282, "o", " commandutils.go:131: DEBUG|20211107-132431|localhost|Throttling connection|0|80\r\n"] +[43.125481, "o", " commandutils.go:131: DEBUG|20211107-132431|localhost|Throttling says that the connection can be established|1|80\r\n"] +[43.125696, "o", " commandutils.go:131: DEBUG|20211107-132431|localhost|Incrementing connection stats\r\n commandutils.go:131: DEBUG|20211107-132431|localhost|Dialing into the connection|localhost:4243\r\n commandutils.go:131: DEBUG|20211107-132431|Ramp up sleeping before processing mapreduce results|500ms\r\n"] +[43.126483, "o", " commandutils.go:131: INFO|20211107-132431|Handling connection\r\n"] +[43.148777, "o", " commandutils.go:131: DEBUG|20211107-132431|backgroundCanSSH|DTAIL-CONTINUOUS@127.0.0.1:59574|dserver_continuous_test|127.0.0.1|dserver_continuous_test|[localhost]\r\n"] +[43.149347, "o", " commandutils.go:131: DEBUG|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|dserver_continuous_test|backgroundCanSSH|Comparing IP addresses|127.0.0.1|::1\r\n commandutils.go:131: DEBUG|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|dserver_continuous_test|backgroundCanSSH|Comparing IP addresses|127.0.0.1|127.0.0.1\r\n"] +[43.149423, "o", " commandutils.go:131: DEBUG|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|Granting SSH connection\r\n"] +[43.150197, "o", " commandutils.go:131: DEBUG|20211107-132431|localhost|Creating SSH session\r\n commandutils.go:131: INFO|20211107-132431|231433|stats.go:53|8|25|13|0.33|40h54m37s|MAPREDUCE:STATS|currentConnections=1|lifetimeConnections=1\r\n commandutils.go:131: INFO|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|Invoking channel handler\r\n commandutils.go:131: INFO|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|Invoking request handler\r\n commandutils.go:131: DEBUG|20211107-132431|localhost|Creating handler for SSH session\r\n"] +[43.150387, "o", " commandutils.go:131: DEBUG|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|Creating new server handler\r\n"] +[43.150628, "o", " commandutils.go:131: DEBUG|20211107-132431|map from INTEGRATIONTEST select last($line),max(foo),min(bar) group by $hostname interval 1 outfile ./dserver2.csv\r\n"] +[43.150853, "o", " commandutils.go:131: DEBUG|20211107-132431|Sending command|localhost|map from INTEGRATIONTEST select last($line),max(foo),min(bar) group by $hostname interval 1 outfile ./dserver2.csv|bWFwIGZyb20gSU5URUdSQVRJT05URVNUIHNlbGVjdCBsYXN0KCRsaW5lKSxtYXgoZm9vKSxtaW4oYmFyKSBncm91cCBieSAkaG9zdG5hbWUgaW50ZXJ2YWwgMSBvdXRmaWxlIC4vZHNlcnZlcjIuY3N2\r\n commandutils.go:131: DEBUG|20211107-132431|tail: ./dserver2.log regex:default \\|MAPREDUCE:INTEGRATIONTEST\\|\r\n commandutils.go:131: DEBUG|20211107-132431|Sending command|localhost|tail: ./dserver2.log regex:default \\|MAPREDUCE:INTEGRATIONTEST\\||dGFpbDogLi9kc2VydmVyMi5sb2cgcmVnZXg6ZGVmYXVsdCBcfE1BUFJFRFVDRTpJTlRFR1JBVElPTlRFU1RcfA==\r\n"] +[43.15102, "o", " commandutils.go:131: DEBUG|20211107-132431|localhost|Unthrottling connection (2)|1|80\r\n"] +[43.151089, "o", " commandutils.go:131: DEBUG|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|protocol 4.1 base64 bWFwIGZyb20gSU5URUdSQVRJT05URVNUIHNlbGVjdCBsYXN0KCRsaW5lKSxtYXgoZm9vKSxtaW4oYmFyKSBncm91cCBieSAkaG9zdG5hbWUgaW50ZXJ2YWwgMSBvdXRmaWxlIC4vZHNlcnZlcjIuY3N2\r\n"] +[43.151617, "o", " commandutils.go:131: DEBUG|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|Handling user command|114|[map from INTEGRATIONTEST select last($line),max(foo),min(bar) group by $hostname interval 1 outfile ./dserver2.csv]\r\n commandutils.go:131: INFO|20211107-132431|Creating log format parser|default\r\n commandutils.go:131: DEBUG|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|protocol 4.1 base64 dGFpbDogLi9kc2VydmVyMi5sb2cgcmVnZXg6ZGVmYXVsdCBcfE1BUFJFRFVDRTpJTlRFR1JBVElPTlRFU1RcfA==\r\n commandutils.go:131: DEBUG|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|Handling user command|64|[tail: ./dserver2.log regex:default \\|MAPREDUCE:INTEGRATIONTEST\\|]\r\n commandutils.go:131: DEBUG|20211107-132431|Reading data from file(s)\r\n commandutils.go:131: DEBUG|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|dserver2.log|readfiles|Checking config permissions\r\n"] +[43.151742, "o", " commandutils.go:131: INFO|20211107-132431|DTAIL-CONTINUOUS@127.0.0.1:59574|Start reading|dserver2.log|dserver2.log\r\n"] +[44.152195, "o", " commandutils.go:131: INFO|20211107-132432|Serializing mapreduce result\r\n"] +[44.625987, "o", " commandutils.go:131: DEBUG|20211107-132432|Gathering interim mapreduce result\r\n commandutils.go:131: DEBUG|20211107-132432|Writing query file|./dserver2.csv.query\r\n"] +[44.626264, "o", " commandutils.go:131: INFO|20211107-132432|Writing outfile|./dserver2.csv\r\n"] +[45.153195, "o", " commandutils.go:131: INFO|20211107-132433|Serializing mapreduce result\r\n"] +[45.15332, "o", " commandutils.go:131: DEBUG|20211107-132433|AGGREGATE|integrationtest|integrationtest∥1∥max(foo)≔1∥min(bar)≔42∥last($line)≔INFO|19801011-424242|1|dserver_test.go|1|1|1|1.0|1m|MAPREDUCE:INTEGRATIONTEST|foo=1|bar=42∥\r\n"] +[45.62719, "o", " commandutils.go:131: DEBUG|20211107-132433|Gathering interim mapreduce result\r\n"] +[45.62743, "o", " commandutils.go:131: DEBUG|20211107-132433|Writing query file|./dserver2.csv.query\r\n"] +[45.627632, "o", " commandutils.go:131: INFO|20211107-132433|Writing outfile|./dserver2.csv\r\n"] +[46.125508, "o", " commandutils.go:131: INFO|20211107-132434|231433|stats.go:71|8|46|13|0.33|40h54m40s|MAPREDUCE:STATS|connected=1|servers=1|connected%=100|new=1|throttle=0|goroutines=46|cgocalls=13|cpu=8\r\n"] +[46.153804, "o", " commandutils.go:131: INFO|20211107-132434|Serializing mapreduce result\r\n commandutils.go:131: DEBUG|20211107-132434|AGGREGATE|integrationtest|integrationtest∥1∥max(foo)≔1∥min(bar)≔42∥last($line)≔INFO|19801011-424242|1|dserver_test.go|1|1|1|1.0|1m|MAPREDUCE:INTEGRATIONTEST|foo=1|bar=42∥\r\n"] +[46.16627, "o", " commandutils.go:131: DEBUG|20211107-132434|dserver2.log|File truncation check\r\n"] +[46.628955, "o", " commandutils.go:131: DEBUG|20211107-132434|Gathering interim mapreduce result\r\n commandutils.go:131: DEBUG|20211107-132434|Writing query file|./dserver2.csv.query\r\n"] +[46.62925, "o", " commandutils.go:131: INFO|20211107-132434|Writing outfile|./dserver2.csv\r\n"] +[47.155066, "o", " commandutils.go:131: INFO|20211107-132435|Serializing mapreduce result\r\n"] +[47.155376, "o", " commandutils.go:131: DEBUG|20211107-132435|AGGREGATE|integrationtest|integrationtest∥1∥max(foo)≔1∥min(bar)≔42∥last($line)≔INFO|19801011-424242|1|dserver_test.go|1|1|1|1.0|1m|MAPREDUCE:INTEGRATIONTEST|foo=1|bar=42∥\r\n"] +[47.630506, "o", " commandutils.go:131: DEBUG|20211107-132435|Gathering interim mapreduce result\r\n commandutils.go:131: DEBUG|20211107-132435|Writing query file|./dserver2.csv.query\r\n"] +[47.630767, "o", " commandutils.go:131: INFO|20211107-132435|Writing outfile|./dserver2.csv\r\n"] +[48.121599, "o", " commandutils.go:131: INFO|20211107-132436|Job exited with status 0\r\n"] +[48.123581, "o", " commandutils.go:138: Command finished with with exit code 0: <nil>\r\n"] +[48.123714, "o", " fileutils.go:77: Comparing files dserver2.csv dserver2.csv.expected\r\n"] +[48.123839, "o", " fileutils.go:118: SHA dserver2.csv WAB6KCgfvStcSSAecKx-YdZZNXXBzMtt6xtuJKpwrwg=\r\n"] +[48.12404, "o", " fileutils.go:118: SHA dserver2.csv.expected WAB6KCgfvStcSSAecKx-YdZZNXXBzMtt6xtuJKpwrwg=\r\n"] +[48.124178, "o", " fileutils.go:77: Comparing files dserver2.csv.query dserver2.csv.query.expected\r\n"] +[48.124284, "o", " fileutils.go:118: SHA dserver2.csv.query vOHTLDQj7qkXB63sOzW8qKQY6jp6zOxJXhmKmHpGHNI=\r\n"] +[48.124428, "o", " fileutils.go:118: SHA dserver2.csv.query.expected vOHTLDQj7qkXB63sOzW8qKQY6jp6zOxJXhmKmHpGHNI=\r\n"] +[48.124784, "o", "--- PASS: TestDServer2 (7.01s)\r\n"] +[48.124902, "o", "=== RUN TestDTailWithServer\r\n"] +[48.125365, "o", " commandutils.go:62: ../dserver --cfg none --logger stdout --logLevel info --bindAddress localhost --port 4244\r\n"] +[48.126773, "o", " commandutils.go:62: ../dtail --cfg none --logger stdout --logLevel info --servers localhost:4244 --files dtail.follow.tmp --grep Hello --trustAllHosts --noColor\r\n"] +[48.130759, "o", " dtail_test.go:92: server: DTail 4.0.0-RC5 Protocol 4.1 Have a lot of fun!\r\n"] +[48.131395, "o", " dtail_test.go:92: server: INFO|20211107-132436|Starting server|DTail 4.0.0-RC5 Protocol 4.1 Have a lot of fun!\r\n"] +[48.131539, "o", " dtail_test.go:92: server: INFO|20211107-132436|Reading private server RSA host key from file|./ssh_host_key\r\n"] +[48.131946, "o", " dtail_test.go:92: server: INFO|20211107-132436|Starting server\r\n"] +[48.13218, "o", " dtail_test.go:92: server: INFO|20211107-132436|Binding server|localhost:4244\r\n"] +[48.133506, "o", " dtail_test.go:92: server: INFO|20211107-132436|Starting continuous job runner after 2s\r\n"] +[48.133557, "o", " dtail_test.go:92: server: INFO|20211107-132436|Starting scheduled job runner after 2s\r\n"] +[48.135201, "o", " dtail_test.go:92: server: INFO|20211107-132436|Handling connection\r\n"] +[48.148541, "o", " dtail_test.go:92: server: INFO|20211107-132436|paul@127.0.0.1:56966|Incoming authorization\r\n"] +[48.148623, "o", " dtail_test.go:92: server: INFO|20211107-132436|paul@127.0.0.1:56966|Reading|./id_rsa.pub\r\n"] +[48.157688, "o", " dtail_test.go:92: server: INFO|20211107-132436|231452|stats.go:53|8|15|9|0.39|40h54m42s|MAPREDUCE:STATS|currentConnections=1|lifetimeConnections=1\r\n"] +[48.157718, "o", " dtail_test.go:92: server: INFO|20211107-132436|paul@127.0.0.1:56966|Invoking channel handler\r\n"] +[48.157803, "o", " dtail_test.go:92: server: INFO|20211107-132436|paul@127.0.0.1:56966|Invoking request handler\r\n"] +[48.158189, "o", " dtail_test.go:92: server: INFO|20211107-132436|paul@127.0.0.1:56966|dtail.follow.tmp|/home/paul/git/dtail/integrationtests/dtail.follow.tmp|readfiles|Calculated new clean path from original file path (possibly symlink)\r\n"] +[48.158259, "o", " dtail_test.go:92: server: INFO|20211107-132436|paul@127.0.0.1:56966|/home/paul/git/dtail/integrationtests/dtail.follow.tmp|readfiles|User with OS file system permissions to path\r\n"] +[48.158314, "o", " dtail_test.go:92: server: INFO|20211107-132436|paul@127.0.0.1:56966|/home/paul/git/dtail/integrationtests/dtail.follow.tmp|Permission test passed partially, matching positive pattern|^/.*\r\n"] +[48.158363, "o", " dtail_test.go:92: server: INFO|20211107-132436|paul@127.0.0.1:56966|Start reading|dtail.follow.tmp|dtail.follow.tmp\r\n"] +[49.162952, "o", " dtail_test.go:94: client: REMOTE|integrationtest|100|1|dtail.follow.tmp|2021-11-07 13:24:37.291805318 +0200 EET m=+28.009754273 - Hello World!\r\n dtail_test.go:99: Received greeting World! 1\r\n"] +[50.166878, "o", " dtail_test.go:94: client: REMOTE|integrationtest|100|2|dtail.follow.tmp|2021-11-07 13:24:38.293444249 +0200 EET m=+29.011393193 - Hello Sol-System!\r\n dtail_test.go:99: Received greeting Sol-System! 2\r\n"] +[51.134514, "o", " dtail_test.go:94: client: CLIENT|integrationtest|INFO|STATS:STATS|servers=1|connected%=100|new=1|throttle=0|goroutines=22|cgocalls=9|cpu=8|connected=1\r\n"] +[51.171256, "o", " dtail_test.go:94: client: REMOTE|integrationtest|100|3|dtail.follow.tmp|2021-11-07 13:24:39.294062973 +0200 EET m=+30.012011908 - Hello Milky-Way!\r\n dtail_test.go:99: Received greeting Milky-Way! 3\r\n"] +[52.175549, "o", " dtail_test.go:94: client: REMOTE|integrationtest|100|4|dtail.follow.tmp|2021-11-07 13:24:40.294334979 +0200 EET m=+31.012283915 - Hello Universe!\r\n dtail_test.go:99: Received greeting Universe! 4\r\n"] +[53.17943, "o", " dtail_test.go:94: client: REMOTE|integrationtest|100|5|dtail.follow.tmp|2021-11-07 13:24:41.295397468 +0200 EET m=+32.013346408 - Hello Multiverse!\r\n dtail_test.go:99: Received greeting Multiverse! 5\r\n"] +[53.180644, "o", "--- PASS: TestDTailWithServer (5.05s)\r\n=== RUN TestDTailColorTable\r\n commandutils.go:23: Creating stdout file dtailcolortable.stdout.tmp\r\n commandutils.go:30: Running command ../dtail --colorTable\r\n"] +[53.18942, "o", " commandutils.go:33: Done running command! <nil>\r\n"] +[53.189549, "o", " fileutils.go:77: Comparing files dtailcolortable.stdout.tmp dtailcolortable.expected\r\n"] +[53.190141, "o", " fileutils.go:118: SHA dtailcolortable.stdout.tmp MuYW7rfmA_NzyGJTWbPQw4xWSybKutw9BH7k1EABOR8=\r\n"] +[53.190738, "o", " fileutils.go:118: SHA dtailcolortable.expected MuYW7rfmA_NzyGJTWbPQw4xWSybKutw9BH7k1EABOR8=\r\n"] +[53.19083, "o", "--- PASS: TestDTailColorTable (0.01s)\r\n"] +[53.190931, "o", "=== RUN TestDTailHealth1\r\n"] +[53.19101, "o", " dtailhealth_test.go:20: Serverless check, is supposed to exit with warning state.\r\n commandutils.go:23: Creating stdout file dtailhealth1.stdout.tmp\r\n"] +[53.191114, "o", " commandutils.go:30: Running command ../dtailhealth \r\n"] +[53.194272, "o", " commandutils.go:33: Done running command! exit status 1\r\n"] +[53.194305, "o", " fileutils.go:77: Comparing files dtailhealth1.stdout.tmp dtailhealth1.expected\r\n"] +[53.194406, "o", " fileutils.go:118: SHA dtailhealth1.stdout.tmp 32cq5tw5HV4Otbdfr95fX9NYowYg6sVJmtk8JaZSOgs=\r\n"] +[53.194461, "o", " fileutils.go:118: SHA dtailhealth1.expected 32cq5tw5HV4Otbdfr95fX9NYowYg6sVJmtk8JaZSOgs=\r\n"] +[53.194583, "o", "--- PASS: TestDTailHealth1 (0.00s)\r\n"] +[53.194649, "o", "=== RUN TestDTailHealth2\r\n"] +[53.194746, "o", " dtailhealth_test.go:42: Negative test, is supposed to exit with a critical state.\r\n"] +[53.194787, "o", " commandutils.go:23: Creating stdout file dtailhealth2.stdout.tmp\r\n"] +[53.19489, "o", " commandutils.go:30: Running command ../dtailhealth --server example:1\r\n"] +[55.200336, "o", " commandutils.go:33: Done running command! exit status 2\r\n"] +[55.200662, "o", " fileutils.go:77: Comparing files dtailhealth2.stdout.tmp dtailhealth2.expected\r\n fileutils.go:118: SHA dtailhealth2.stdout.tmp sB-5vdzv9m_GLo4B2buepMWru52AGV3kOFIfPPCvL_g=\r\n"] +[55.200799, "o", " fileutils.go:118: SHA dtailhealth2.expected sB-5vdzv9m_GLo4B2buepMWru52AGV3kOFIfPPCvL_g=\r\n"] +[55.20105, "o", "--- PASS: TestDTailHealth2 (2.01s)\r\n"] +[55.201254, "o", "=== RUN TestDTailHealthCheck3\r\n"] +[55.201524, "o", " commandutils.go:62: ../dserver --cfg none --logger stdout --logLevel trace --bindAddress localhost --port 4245\r\n"] +[56.203582, "o", " commandutils.go:23: Creating stdout file dtailhealth3.stdout.tmp\r\n"] +[56.203727, "o", " commandutils.go:30: Running command ../dtailhealth --server localhost:4245\r\n"] +[56.227392, "o", " commandutils.go:33: Done running command! <nil>\r\n"] +[56.227507, "o", " fileutils.go:94: Checking if file contains string dtailhealth3.stdout.tmp OK: All fine at localhost:4245 :-)\r\n fileutils.go:16: Mapping dtailhealth3.stdout.tmp\r\n fileutils.go:102: OK: All fine at localhost:4245 :-)\r\n"] +[56.227646, "o", "--- PASS: TestDTailHealthCheck3 (1.03s)"] +[56.227684, "o", "\r\n"] +[56.227811, "o", "PASS"] +[56.227841, "o", "\r\n"] +[56.250585, "o", "ok \tgithub.com/mimecast/dtail/integrationtests\t35.139s\r\n"] +[56.441433, "o", "=== RUN TestColors\r\n"] +[56.442343, "o", " color_test.go:37: \u001b[30m Mimecast \u001b[39m\u001b[40m Mimecast \u001b[49m\u001b[31m Mimecast \u001b[39m\u001b[41m Mimecast \u001b[49m\u001b[32m Mimecast \u001b[39m\u001b[42m Mimecast \u001b[49m\u001b[33m Mimecast \u001b[39m\u001b[43m Mimecast \u001b[49m\u001b[34m Mimecast \u001b[39m\u001b[44m Mimecast \u001b[49m\u001b[35m Mimecast \u001b[39m\u001b[45m Mimecast \u001b[49m\u001b[36m Mimecast \u001b[39m\u001b[46m Mimecast \u001b[49m\u001b[37m Mimecast \u001b[39m\u001b[47m Mimecast \u001b[49m\u001b[39m Mimecast \u001b[39m\u001b[49m Mimecast \u001b[49m\u001b[30m\u001b[41m Mimecast \u001b[49m\u001b[39m\u001b[30m\u001b[42m Mimecast \u001b[49m\u001b[39m\u001b[30m\u001b[43m Mimecast \u001b[49m\u001b[39m\u001b[30m\u001b[44m Mimecast \u001b[49m\u001b[39m\u001b[30m\u001b[45m Mimecast \u001b[49m\u001b[39m\u001b[30m\u001b[46m Mimecast \u001b[49m\u001b[39m\u001b[30m\u001b[47m Mimecast \u001b[49m\u001b[39m\u001b[30m\u001b[49m Mimecast \u001b[49m\u001b[39m\u001b[31m\u001b[40m Mimecast \u001b[49m\u001b[39m\u001b[31m\u001b[42m Mimecast \u001b[49m\u001b[39m\u001b[31m\u001b[43m Mimecast \u001b[49m\u001b[39m\u001b[31m\u001b[44m Mimecast \u001b[49m\u001b[39m\u001b[31m\u001b[45m Mimecast \u001b[49m\u001b[39m\u001b[31m\u001b[46m Mimecast \u001b[49m\u001b[39m\u001b[31m\u001b[47m Mimecast \u001b[49m\u001b[39m\u001b[31m\u001b[49m Mimecast \u001b[49m\u001b[39m\u001b[32m\u001b[40m Mimecast \u001b[49m\u001b[39m\u001b[32m\u001b[41m Mimecast \u001b[49m\u001b[39m\u001b[32m\u001b[43m Mimecast \u001b[49m\u001b[39m\u001b[32m\u001b[44m Mimecast \u001b[49m\u001b[39m\u001b[32m\u001b[45m Mimecast \u001b[49m\u001b[39m\u001b[32m\u001b[46m M"] +[56.442434, "o", "imecast \u001b[49m\u001b[39m\u001b[32m\u001b[47m Mimecast \u001b[49m\u001b[39m\u001b[32m\u001b[49m Mimecast \u001b[49m\u001b[39m\u001b[33m\u001b[40m Mimecast \u001b[49m\u001b[39m\u001b[33m\u001b[41m Mimecast \u001b[49m\u001b[39m\u001b[33m\u001b[42m Mimecast \u001b[49m\u001b[39m\u001b[33m\u001b[44m Mimecast \u001b[49m\u001b[39m\u001b[33m\u001b[45m Mimecast \u001b[49m\u001b[39m\u001b[33m\u001b[46m Mimecast \u001b[49m\u001b[39m\u001b[33m\u001b[47m Mimecast \u001b[49m\u001b[39m\u001b[33m\u001b[49m Mimecast \u001b[49m\u001b[39m\u001b[34m\u001b[40m Mimecast \u001b[49m\u001b[39m\u001b[34m\u001b[41m Mimecast \u001b[49m\u001b[39m\u001b[34m\u001b[42m Mimecast \u001b[49m\u001b[39m\u001b[34m\u001b[43m Mimecast \u001b[49m\u001b[39m\u001b[34m\u001b[45m Mimecast \u001b[49m\u001b[39m\u001b[34m\u001b[46m Mimecast \u001b[49m\u001b[39m\u001b[34m\u001b[47m Mimecast \u001b[49m\u001b[39m\u001b[34m\u001b[49m Mimecast \u001b[49m\u001b[39m\u001b[35m\u001b[40m Mimecast \u001b[49m\u001b[39m\u001b[35m\u001b[41m Mimecast \u001b[49m\u001b[39m\u001b[35m\u001b[42m Mimecast \u001b[49m\u001b[39m\u001b[35m\u001b[43m Mimecast \u001b[49m\u001b[39m\u001b[35m\u001b[44m Mimecast \u001b[49m\u001b[39m\u001b[35m\u001b[46m Mimecast \u001b[49m\u001b[39m\u001b[35m\u001b[47m Mimecast \u001b[49m\u001b[39m\u001b[35m\u001b[49m Mimecast \u001b[49m\u001b[39m\u001b[36m\u001b[40m Mimecast \u001b[49m\u001b[39m\u001b[36m\u001b[41m Mimecast \u001b[49m\u001b[39m\u001b[36m\u001b[42m Mimecast \u001b[49m\u001b[39m\u001b[36m\u001b[43m Mimecast \u001b[49m\u001b[39m\u001b[36m\u001b[44m Mimecast \u001b[49m\u001b[39m\u001b[36m\u001b[45m Mimecast \u001b[49m\u001b[39m\u001b[36m\u001b[47m Mimecast \u001b[49m\u001b[39m\u001b[36m\u001b[49m Mimec"] +[56.442456, "o", "ast \u001b[49m\u001b[39m\u001b[37m\u001b[40m Mimecast \u001b[49m\u001b[39m\u001b[37m\u001b[41m Mimecast \u001b[49m\u001b[39m\u001b[37m\u001b[42m Mimecast \u001b[49m\u001b[39m\u001b[37m\u001b[43m Mimecast \u001b[49m\u001b[39m\u001b[37m\u001b[44m Mimecast \u001b[49m\u001b[39m\u001b[37m\u001b[45m Mimecast \u001b[49m\u001b[39m\u001b[37m\u001b[46m Mimecast \u001b[49m\u001b[39m\u001b[37m\u001b[49m Mimecast \u001b[49m\u001b[39m\u001b[39m\u001b[40m Mimecast \u001b[49m\u001b[39m\u001b[39m\u001b[41m Mimecast \u001b[49m\u001b[39m\u001b[39m\u001b[42m Mimecast \u001b[49m\u001b[39m\u001b[39m\u001b[43m Mimecast \u001b[49m\u001b[39m\u001b[39m\u001b[44m Mimecast \u001b[49m\u001b[39m\u001b[39m\u001b[45m Mimecast \u001b[49m\u001b[39m\u001b[39m\u001b[46m Mimecast \u001b[49m\u001b[39m\u001b[39m\u001b[47m Mimecast \u001b[49m\u001b[39m\r\n--- PASS: TestColors (0.00s)\r\n=== RUN TestAttributes\r\n"] +[56.442613, "o", " color_test.go:52: \u001b[37m\u001b[44m\u001b[1m Mimecast \u001b[0m\u001b[49m\u001b[39m\u001b[37m\u001b[44m\u001b[2m Mimecast \u001b[0m\u001b[49m\u001b[39m\u001b[37m\u001b[44m\u001b[3m Mimecast \u001b[0m\u001b[49m\u001b[39m\u001b[37m\u001b[44m\u001b[4m Mimecast \u001b[0m\u001b[49m\u001b[39m\u001b[37m\u001b[44m\u001b[5m Mimecast \u001b[0m\u001b[49m\u001b[39m\u001b[37m\u001b[44m\u001b[5m Mimecast \u001b[0m\u001b[49m\u001b[39m\u001b[37m\u001b[44m\u001b[6m Mimecast \u001b[0m\u001b[49m\u001b[39m\u001b[37m\u001b[44m\u001b[7m Mimecast \u001b[0m\u001b[49m\u001b[39m\u001b[37m\u001b[44m\u001b[8m Mimecast \u001b[0m\u001b[49m\u001b[39m\u001b[37m\u001b[44m Mimecast \u001b[49m\u001b[39m\r\n"] +[56.442677, "o", "--- PASS: TestAttributes (0.00s)\r\n"] +[56.442844, "o", "PASS\r\n"] +[56.4564, "o", "ok \tgithub.com/mimecast/dtail/internal/color\t0.019s\r\n"] +[56.563708, "o", "? \tgithub.com/mimecast/dtail/internal/io/fs/permissions\t[no test files]\r\n"] +[56.858991, "o", "=== RUN TestParseQuerySimple\r\n"] +[56.85944, "o", "--- PASS: TestParseQuerySimple (0.00s)\r\n"] +[56.859516, "o", "=== RUN TestParseQueryDeep\r\n"] +[56.859888, "o", " query_test.go:70: Query(Select:[selectCondition(Field:s1,FieldStorage:s1,Operation:5) selectCondition(Field:from,FieldStorage:from,Operation:5) selectCondition(Field:s3,FieldStorage:count(s3),Operation:1)],Table:TABLE,Where:[{1 w1 0 10 3 2 2} {1 w2 0 1 2 free beer 0}],Set:[{$foo 4 bar 0 [{maskdigits 0x64fc40}]} {$baz 3 12 12 []} {$bay 1 $foo 0 []}]GroupBy:[g1 g2],GroupKey:g1,g2,OrderBy:count(s3),ReverseOrder:false,Interval:10s,Limit:23,Outfile:,RawQuery:select s1, `from`, count(s3) from table where w1 == 2 and w2 eq \"free beer\" group by g1, g2 order by count(s3) interval 10 limit 23 set $foo = maskdigits(bar), $baz = 12, $bay = $foo logformat generic,tokens:[select s1 `from` count(s3) from table where w1 == 2 and w2 eq free beer group by g1 g2 order by count(s3) interval 10 limit 23 set $foo = maskdigits(bar) $baz = 12 $bay = $foo logformat generic],LogFormat:generic)\r\n"] +[56.860182, "o", " query_test.go:70: Query(Select:[selectCondition(Field:s1,FieldStorage:s1,Operation:5) selectCondition(Field:from,FieldStorage:from,Operation:5) selectCondition(Field:s3,FieldStorage:count(s3),Operation:1)],Table:TABLE,Where:[{1 w1 0 10 3 2 2} {1 w2 0 1 2 free beer 0}],Set:[{$foo 4 bar 0 [{maskdigits 0x64fc40}]} {$baz 3 12 12 []} {$bay 1 $foo 0 []}]GroupBy:[g1 g2],GroupKey:g1,g2,OrderBy:count(s3),ReverseOrder:false,Interval:10s,Limit:23,Outfile:,RawQuery:SELECT s1, `from`, COUNT(s3) FROM table WHERE w1 == 2 AND w2 eq \"free beer\" GROUP g1, g2 ORDER count(s3) INTERVAL 10 LIMIT 23 SET $foo = maskdigits(bar), $baz = 12, $bay = $foo logformat generic,tokens:[SELECT s1 `from` COUNT(s3) FROM table WHERE w1 == 2 AND w2 eq free beer GROUP g1 g2 ORDER count(s3) INTERVAL 10 LIMIT 23 SET $foo = maskdigits(bar) $baz = 12 $bay = $foo logformat generic],LogFormat:generic)\r\n"] +[56.860253, "o", "--- PASS: TestParseQueryDeep (0.00s)\r\n"] +[56.860342, "o", "PASS\r\n"] +[56.872784, "o", "ok \tgithub.com/mimecast/dtail/internal/mapr\t0.020s\r\n"] +[57.059725, "o", "=== RUN TestFunction\r\n"] +[57.060102, "o", " function_test.go:16: md5sum($line) [{md5sum 0x5aa600}] $line\r\n"] +[57.060211, "o", " function_test.go:33: maskdigits(md5sum(maskdigits($line))) [{maskdigits 0x5aa4a0} {md5sum 0x5aa600} {maskdigits 0x5aa4a0}] $line\r\n"] +[57.060369, "o", "--- PASS: TestFunction (0.00s)\r\n"] +[57.06057, "o", "PASS\r\n"] +[57.073204, "o", "ok \tgithub.com/mimecast/dtail/internal/mapr/funcs\t0.021s\r\n"] +[57.377456, "o", "=== RUN TestDefaultLogFormat\r\n"] +[57.377861, "o", "--- PASS: TestDefaultLogFormat (0.00s)\r\n"] +[57.377981, "o", "PASS\r\n"] +[57.399632, "o", "ok \tgithub.com/mimecast/dtail/internal/mapr/logformat\t0.032s\r\n"] +[57.583071, "o", "=== RUN TestRegex\r\n--- PASS: TestRegex (0.00s)\r\n"] +[57.583215, "o", "PASS\r\n"] +[57.596594, "o", "ok \tgithub.com/mimecast/dtail/internal/regex\t0.020s\r\n"] +[57.600652, "o", "\u001b[1m\u001b[7m%\u001b[27m\u001b[1m\u001b[0m \r \r"] +[57.601594, "o", "\u001b]7;file://earth/home/paul/git/dtail\u001b\\"] +[57.617681, "o", "\r\u001b[0m\u001b[27m\u001b[24m\u001b[J\r\n\u001b[1;33mpaul\u001b[0m in \u001b[1;2;32mearth\u001b[0m in \u001b[1;36mdtail\u001b[0m on \u001b[1;35m🌱 \u001b[0m\u001b[1;35m4.0.0-RC\u001b[0m \u001b[1;31m[\u001b[0m\u001b[1;31m?\u001b[0m\u001b[1;31m]\u001b[0m via \u001b[1;36m🏎💨 \u001b[0m\u001b[1;36mv1.16.8\u001b[0m\u001b[1;36m \u001b[0mcmdruntime \r\n\u001b[1;32m❯\u001b[0m \u001b[K\u001b[?2004h"] +[58.953332, "o", "\u001b[?2004l\r\r\n"] diff --git a/doc/testing.gif b/doc/testing.gif Binary files differnew file mode 100644 index 0000000..696921d --- /dev/null +++ b/doc/testing.gif diff --git a/doc/testing.md b/doc/testing.md index 92455c9..0e802a7 100644 --- a/doc/testing.md +++ b/doc/testing.md @@ -45,6 +45,8 @@ To run the integration test together with all the unit tests simply run `make te % go test -race -v ./integrationtests ``` + + ## Semi-manual tests with DTail server instances running in Docker ### Requirements |
