diff --git a/contrib/cirrus/logformatter b/contrib/cirrus/logformatter index 739fa57d16..14b278521b 100755 --- a/contrib/cirrus/logformatter +++ b/contrib/cirrus/logformatter @@ -18,7 +18,7 @@ use warnings; (our $ME = $0) =~ s|.*/||; -our $VERSION = '0.2'; +our $VERSION = '0.3'; # Autoflush stdout $| = 1; @@ -46,8 +46,12 @@ table.synopsis { border: none; border-collapse: collapse; margin-left: 2em; marg .log-debug { color: #999; } .log-info { color: #333; } .log-warning { color: #f60; } -.log-error { color: #900; font-weight: bold; } -.log-skip { color: #F90; } +.log-error { background: #fee; color: #900; font-weight: bold; } +.error-retry { color: #c33; font-size: 125%; font-weight: bold; border-top: 1px solid #f00; } +.log-passed { color: #393; } +.log-failed { color: #F00; font-weight: bold; font-size: 150%; } +.log-skipped { color: #F90; } +.log-flakey { background: #f93; font-weight: bold; font-size: 150%; } .log-slow { background: #FF0; color: #000; font-weight: bold; } .subtest { background: #eee; } .subsubtest { color: #F39; font-weight: bold; } @@ -55,6 +59,22 @@ table.synopsis { border: none; border-collapse: collapse; margin-left: 2em; marg .command { font-weight: bold; color: #000; } .changed { color: #000; font-weight: bold; } +/* Ginkgo "Enter/Exit [foo]": visually separate from each other */ +.ginkgo-timeline { margin-top: 1ex; margin-bottom: 1ex; } + +/* BeforeEach and AfterEach tend to be boring. Gray them out. */ +.ginkgo-beforeeach { background: #f0f0f0; color: #999; } +.ginkgo-aftereach { background: #f0f0f0; color: #999; } +.ginkgo-beforeeach b { color: #000; } +.ginkgo-aftereach b { color: #000; } + +/* [It] is where the test happens but we don't need any special highlights */ +.ginkgo-it { } + +/* Finall summary line at bottom */ +.ginkgo-final-success { background: #393; font-weight: bold; font-size: 150%; } +.ginkgo-final-fail { background: #f00; font-weight: bold; font-size: 150%; } + /* links to source files: not as prominent as links to errors */ a.codelink:link { color: #000; } a.codelink:visited { color: #666; } @@ -67,6 +87,7 @@ a.timing { text-decoration: none; } /* BATS styles */ .bats-passed { color: #393; } .bats-failed { color: #F00; font-weight: bold; } +.bats-flaked { color: #F93; font-weight: bold; } .bats-skipped { color: #F90; } .bats-log { color: #933; } .bats-log-failblock { color: #b00; background-color: #fee; display: inline-flex; margin: 0 -500%; padding: 0 500% !important; } @@ -210,6 +231,7 @@ END_HTML my $previous_timestamp = ''; # timestamp of previous line my $cirrus_task; # Cirrus task number, used for linking my $git_commit; # git SHA, used for linking to source files + my $subtest_status; # pass, fail, skip, flake - for each subtest my $in_failure; # binary flag: are we in an error dump? my $in_timing; # binary flag: are we in the timing section? my $after_divider = 0; # Count of lines after seeing '-----' @@ -230,6 +252,12 @@ END_HTML # Main loop: read input, one line at a time, and write out reformatted LINE: while (my $line = ) { + # ARGH. Some log files have NUL characters , apparently because + # certain tests write tar to stdout. Bleagh. Although it seems + # rude to strip those from our (purportedly untouched) log, + # it's worse to read log files with random NULs. + $line =~ s/\0//g; + print $line; # Immediately dump back to stdout $Prompt = '$' if $line =~ /Runner executing .* as rootless /; @@ -238,7 +266,6 @@ END_HTML next LINE if ! $out_fh; chomp $line; - $line =~ s/\0//g; # Some log files have NULs???? $line = escapeHTML($line); # Temporarily strip off leading timestamp @@ -251,8 +278,8 @@ END_HTML $previous_timestamp = $timestamp; } - # Helper function for printing a formatted line. This should always - # be followed by 'next LINE'. + # Helper function for printing a formatted line. This should almost + # always be followed by 'next LINE'. my $print_line = sub { my $css = shift; @@ -264,6 +291,20 @@ END_HTML print { $out_fh } "\n"; }; + # ARGH! Special case for tests that run 'cat /proc/self/attr/current: + # that file terminates with NUL, so ginkgo logs concatenate the next + # output line, which is usually "Running: next-podman-command". + # This makes the log readable by splitting into two lines. + if ($line =~ /^(\s*)(\S+_u:\S+_t:\S+:c\S+)(Running:.*)/) { + my ($indent, $selinux, $nextline) = ($1||'', $2, $3); + # Print the SELinux line, unmodified... + $line = $indent . $selinux; + $print_line->(); + # ...then forget that, and continue processing (fall through) + # with the 'Running' line. + $line = $indent . $nextline; + } + # Try to identify the git commit we're working with... if ($line =~ m!/define.gitCommit=([0-9a-f]+)!) { $git_commit = $1; @@ -384,18 +425,51 @@ END_HTML next LINE; } + # + # Must be ginkgo + # + if ($line =~ s!^(\s*)(&[gl]t;)\s+(Enter|Exit)\s+\[(\w+)\]!!) { + my ($indent, $arrow, $action, $block) = ($1, $2, $3, $4); + if ($action eq 'Enter') { + printf { $out_fh } "
", + lc($block); + $line = "$indent→ Enter [$block]$line"; + $print_line->(); + } + else { + # Can be a div within a div + if ($in_failure) { + $in_failure = 0; + print { $out_fh } "
"; + } + + $line = "$indent← Exit [$block]$line"; + $print_line->(); + + print { $out_fh } ""; + } + + next LINE; + } + + # Ginkgo v2 nicely lumps all retries for the same given test + if ($line =~ /^\s*Attempt\s+\#\d+\s+Failed\.\s+Retr/) { + $print_line->("error-retry"); + next LINE; + } + # Timing section at the bottom of the page if ($line =~ / timing results\s*$/) { $in_timing = 1; } elsif ($in_timing) { - if ($line =~ /^(\S.*\S)\s+(\d+\.\d+)\s*$/) { + if ($line =~ /^\s*(\S.*\S)\s+(\d+\.\d+)\s*$/) { my ($name, $time) = ($1, $2); my $id = make_id($1, 'timing'); # Try to column-align the timing numbers. Some test names # will be longer than our max - oh well. - my $spaces = 80 - length(unescapeHTML($name)); + my $spaces = 90 - length(unescapeHTML($name)); $spaces = 1 if $spaces < 1; $spaces++ if $time < 10; my $spacing = ' ' x $spaces; @@ -406,10 +480,38 @@ END_HTML } } + # Ginkgo summary line. Colorize Passed, Failed, Flaked, Skipped + if ($line =~ /^(\s*)(FAIL|SUCCESS)!(\s+--\s+)(.*\d+\sPassed.*)/) { + my ($indent, $status, $dashes, $rhs) = ($1, $2, $3, $4); + my @counts = split('\|', $rhs); + my @formatted; + for my $c (@counts) { + $c =~ /^(\s*)(\d+)\s+(\w+)(\s*)$/ + or warn "$ME: line $.: WEIRD: '$c' in '$rhs' from '$line'"; + my ($lhs, $n, $category, $rhs) = ($1||'', $2, $3, $4||''); + # Only highlight if count > 0, so "0 Failed" doesn't yell + if ($n > 0) { + # Yes, we use 'bats-xxxx' classes even though we're ginkgo. + push @formatted, sprintf("%s%d %s%s", + $lhs, lc($category), $n, $category, $rhs); + } + else { + # Zero. Leave unhighlighted. + push @formatted, $c; + } + } + $line = sprintf("%s%s!%s%s", + $indent, + lc($status), $status, + $dashes, join('|', @formatted)); + $print_line->(); + next LINE; + } + # # Ginkgo error reformatting # - if ($line =~ /^.{1,4} (Failure|Panic)( in .*)? \[/) { + if ($line =~ /^\s*\[(FAILED|PANICKED)\]/) { # Begins a block of multiple lines including a stack trace print { $out_fh } "
\n"; $in_failure = 1; @@ -428,7 +530,7 @@ END_HTML next LINE; } # (bindings test sometimes emits 'Running' with leading bullet char) - elsif ($line =~ s!^•?Running:!$Prompt!) { + elsif ($line =~ s!^•?(\s*)Running:!$1$Prompt!) { # Highlight the important (non-boilerplate) podman command. $line =~ s/\s+--remote\s+/ /g; # --remote takes no args # Strip out the global podman options, but show them on hover @@ -449,15 +551,7 @@ END_HTML $current_output = ''; } - # Grrr. 'output:' usually just tells us what we already know. - elsif ($line =~ /^output:/) { - $current_output =~ s!^\s+|\s+$!!g; # Trim leading/trailing blanks - $current_output =~ s/\s+/ /g; # Collapse multiple spaces - if ($line eq "output: $current_output" || $line eq 'output: ') { - next LINE; - } - } - elsif ($line =~ /^Error:/ || $line =~ / level=(warning|error) /) { + elsif ($line =~ /^\s*Error:/ || $line =~ / level=(warning|error) /) { $line = "" . $line . ""; } elsif ($line =~ /^panic:/) { @@ -467,23 +561,35 @@ END_HTML $current_output .= ' ' . $line; } + # One line after each divider, there's a status/timing line. + if ($after_divider == 1) { + $line =~ s{(\[(\d+)\.\d+\s+seconds\])}{ + if ($2 > 5) { "$1" } + else { "$1" } + }e; - # Two lines after each divider, there's a test name. Make it + # Make FAILED and SKIPPING visible + $subtest_status = 'passed'; + if ($line =~ s!^(.*\[(SKIPPED|FAILED|FLAKEY).*\].*)!$1!) { + $subtest_status = lc($2); + } + + # FIXME: gray out entire block if it's skipped? + } + + # Four lines after each divider, there's a test name. Make it # an anchor so we can link to it later. - if ($after_divider++ == 2) { + if ($after_divider == 4) { # Sigh. There is no actual marker. Assume that anything with ## two leading spaces then alpha or hyphen (not slashes) is ## a test name. - if ($line =~ /^ [a-zA-Z-]/) { - my $id = make_id($line, 'anchor'); + if ($line =~ /^ (\[It\]\s*)?([a-zA-Z-].*\S)/) { + my $id = make_id($2, 'anchor'); - $line = "

$line

"; + $line = "

$line

"; } } - - # Make SKIPPING and SLOW TEST visible - $line =~ s!(\[SKIPPING\].*)!$1!; - $line =~ s!(\[SLOW TEST.*\])!$1!; + ++$after_divider; # Highlight test name when it appears in the middle of commands. # But make it boring, because we already have the test name in large @@ -491,12 +597,12 @@ END_HTML $line =~ s!^(\s*)(\[It\]\s+.*)!$1$2!; # Failure name corresponds to a previously-seen block. - # 1 2 2 3 3 14 4 - if ($line =~ /^(\[(Fail|Panic!)\] .* \[(It|BeforeEach)\] )([A-Za-z-].*)/) { + # 1 2 2 3 3 14 4 + if ($line =~ /^(\s*\[(FAIL|PANIC!)\] .* \[(It|BeforeEach|AfterEach)\] )([A-Za-z-].*)/) { my ($lhs, $type, $ginkgo_fluff, $testname) = ($1, $2, $3, $4); my $id = make_id($testname, 'link'); - $line = "$lhs$testname"; + $line = "$lhs$testname"; } print { $out_fh } "$timestamp" @@ -603,6 +709,7 @@ sub make_id { state %counter; $name =~ s/^\s+|\s+$//g; # strip leading/trailing whitespace + $name =~ s/^\[It\]\s*//; # strip leading "[It] " $name =~ s/\&#\d+;//g; # 'doesn't' -> 'doesnt' $name =~ s/\"/-/g; # '"path"' -> '-path-' $name =~ s/[^a-zA-Z0-9_-]/-/g; # Convert non-alphanumeric to dash diff --git a/contrib/cirrus/logformatter.t b/contrib/cirrus/logformatter.t index 17867ca80c..3bc8344257 100755 --- a/contrib/cirrus/logformatter.t +++ b/contrib/cirrus/logformatter.t @@ -123,165 +123,122 @@ ok 4 blah == simple ginkgo <<< -$SCRIPT_BASE/integration_test.sh |& ${TIMESTAMP} -[08:26:19] START - All [+xxxx] lines that follow are relative to right now. -[+0002s] GO111MODULE=on go build -mod=vendor -gcflags 'all=-trimpath=/var/tmp/go/src/github.com/containers/podman' -asmflags 'all=-trimpath=/var/tmp/go/src/github.com/containers/podman' -ldflags '-X github.com/containers/podman/libpod/define.gitCommit=40f5d8b1becd381c4e8283ed3940d09193e4fe06 -X github.com/containers/podman/libpod/define.buildInfo=1582809981 -X github.com/containers/podman/libpod/config._installPrefix=/usr/local -X github.com/containers/podman/libpod/config._etcDir=/etc -extldflags ""' -tags " selinux systemd exclude_graphdriver_devicemapper seccomp varlink" -o bin/podman github.com/containers/podman/cmd/podman -[+0103s] • -[+0103s] ------------------------------ -[+0103s] Podman pod restart -[+0103s] podman pod restart single empty pod -[+0103s] /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_restart_test.go:41 -[+0103s] [BeforeEach] Podman pod restart -[+0103s] /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_restart_test.go:18 -[+0103s] [It] podman pod restart single empty pod -[+0103s] /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_restart_test.go:41 -[+0103s] Running: /var/tmp/go/src/github.com/containers/podman/bin/quadlet -no-kmsg-log /tmp/podman_test620310100/generated with QUADLET_UNIT_DIRS=/tmp/podman_test620310100/quadlet -[+0103s] quadlet-generator[98757]: Error converting 'range.less-subnet.network', ignoring: cannot set more ranges than subnets -[+0103s] Running: /var/tmp/go/src/github.com/containers/podman/bin/podman --network-backend netavark --storage-opt vfs.imagestore=/tmp/podman/imagecachedir --root /tmp/podman_test553496330/crio --runroot /tmp/podman_test553496330/crio-run --runtime /usr/bin/runc --conmon /usr/bin/conmon --network-config-dir /etc/cni/net.d --cgroup-manager systemd --tmpdir /tmp/podman_test553496330 --events-backend file --storage-driver vfs pod create --infra=false --share -[+0103s] 4810be0cfbd42241e349dbe7d50fbc54405cd320a6637c65fd5323f34d64af89 -[+0103s] output: 4810be0cfbd42241e349dbe7d50fbc54405cd320a6637c65fd5323f34d64af89 -[+0103s] Running: /var/tmp/go/src/github.com/containers/podman/bin/podman --storage-opt vfs.imagestore=/tmp/podman/imagecachedir --root /tmp/podman_test553496330/crio --runroot /tmp/podman_test553496330/crio-run --runtime /usr/bin/runc --conmon /usr/bin/conmon --network-config-dir /etc/cni/net.d --cgroup-manager systemd --tmpdir /tmp/podman_test553496330 --events-backend file --storage-driver vfs pod restart 4810be0cfbd42241e349dbe7d50fbc54405cd320a6637c65fd5323f34d64af89 -[+0103s] Error: no containers in pod 4810be0cfbd42241e349dbe7d50fbc54405cd320a6637c65fd5323f34d64af89 have no dependencies, cannot start pod: no such container -[+0103s] output: -[+0103s] [AfterEach] Podman pod restart -[+0103s] /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_restart_test.go:28 -[+0103s] Running: /var/tmp/go/src/github.com/containers/podman/bin/podman --storage-opt vfs.imagestore=/tmp/podman/imagecachedir --root /tmp/podman_test553496330/crio --runroot /tmp/podman_test553496330/crio-run --runtime /usr/bin/runc --conmon /usr/bin/conmon --network-config-dir /etc/cni/net.d --cgroup-manager systemd --tmpdir /tmp/podman_test553496330 --events-backend file --storage-driver vfs pod rm -fa -[+0103s] 4810be0cfbd42241e349dbe7d50fbc54405cd320a6637c65fd5323f34d64af89 -[+0104s] Running: /var/tmp/go/src/github.com/containers/libpod/bin/podman-remote --storage-opt vfs.imagestore=/tmp/podman/imagecachedir --root /tmp/podman_test553496330/crio --runroot /tmp/podman_test553496330/crio-run --runtime /usr/bin/runc --conmon /usr/bin/conmon --network-config-dir /etc/cni/net.d --cgroup-manager systemd --tmpdir /tmp/podman_test553496330 --events-backend file --storage-driver vfs --remote --url unix:/run/user/12345/podman-xyz.sock pod rm -fa -[+0104s] 4810be0cfbd42241e349dbe7d50fbc54405cd320a6637c65fd5323f34d64af89 again - - -[+0107s] • -[+0523s] ------------------------------ -[+0523s] Podman play kube with build -[+0523s] --build should override image in store -[+0523s] /var/tmp/go/src/github.com/containers/podman/test/e2e/play_build_test.go:215 - - -[+0479s] • -[+0479s] ------------------------------ -[+0479s] Podman pod rm -[+0479s] podman pod rm -a doesn't remove a running container -[+0479s] /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_rm_test.go:119 - - -[+1405s] • -[+1405s] ------------------------------ -[+1405s] Podman run entrypoint -[+1405s] podman run entrypoint == [""] -[+1405s] /var/tmp/go/src/github.com/containers/podman/test/e2e/run_entrypoint_test.go:47 - -[+0184s] S [SKIPPING] [3.086 seconds] -[+1385s] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds] - -[+1512s] Summarizing 6 Failures: -[+1512s] -[+1512s] [Fail] Podman play kube with build [It] --build should override image in store -[+1512s] /var/tmp/go/src/github.com/containers/podman/test/e2e/play_build_test.go:259 +[05:47:08] START - All [+xxxx] lines that follow are relative to 2023-04-17T05:47:08. +[+0004s] CGO_ENABLED=1 GOOS=linux GOARCH=amd64 go build -ldflags '-X github.com/containers/podman/v4/libpod/define.gitCommit=074143b0fac7af72cd92048d27931a92fe745084 -X github.com/containers/podman/v4/libpod/define.buildInfo=1681728432 -X github.com/containers/podman/v4/libpod/config._installPrefix=/usr/local -X github.com/containers/podman/v4/libpod/config._etcDir=/usr/local/etc -X github.com/containers/podman/v4/pkg/systemd/quadlet._binDir=/usr/local/bin -X github.com/containers/common/pkg/config.additionalHelperBinariesDir= ' -tags " selinux systemd exclude_graphdriver_devicemapper seccomp" -o test/checkseccomp/checkseccomp ./test/checkseccomp +[+0006s] CGO_ENABLED=1 GOOS=linux GOARCH=amd64 go build -ldflags '-X github.com/containers/podman/v4/libpod/define.gitCommit=074143b0fac7af72cd92048d27931a92fe745084 -X github.com/containers/podman/v4/libpod/define.buildInfo=1681728434 -X github.com/containers/podman/v4/libpod/config._installPrefix=/usr/local -X github.com/containers/podman/v4/libpod/config._etcDir=/usr/local/etc -X github.com/containers/podman/v4/pkg/systemd/quadlet._binDir=/usr/local/bin -X github.com/containers/common/pkg/config.additionalHelperBinariesDir= ' -o test/goecho/goecho ./test/goecho +[+0006s] ./hack/install_catatonit.sh +[+0270s] ------------------------------ +[+0271s] • [3.327 seconds] +[+0271s] Podman restart +[+0271s] /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:14 +[+0271s] podman restart non-stop container with short timeout +[+0271s] /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:148 +[+0271s] +[+0271s] Timeline >> +[+0271s] > Enter [BeforeEach] Podman restart - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:21 @ 04/17/23 10:00:28.653 +[+0271s] < Exit [BeforeEach] Podman restart - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:21 @ 04/17/23 10:00:28.653 (0s) +[+0271s] > Enter [It] podman restart non-stop container with short timeout - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:148 @ 04/17/23 10:00:28.653 +[+0271s] Running: /var/tmp/go/src/github.com/containers/podman/bin/podman --storage-opt vfs.imagestore=/tmp/imagecachedir --root /tmp/podman_test2968516396/root --runroot /tmp/podman_test2968516396/runroot --runtime crun --conmon /usr/bin/conmon --network-config-dir /tmp/podman_test2968516396/root/etc/networks --network-backend netavark --cgroup-manager systemd --tmpdir /tmp/podman_test2968516396 --events-backend file --db-backend sqlite --storage-driver vfs run -d --name test1 --env STOPSIGNAL=SIGKILL quay.io/libpod/alpine:latest sleep 999 +[+0271s] 7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc +[+0271s] Running: /var/tmp/go/src/github.com/containers/podman/bin/podman --storage-opt vfs.imagestore=/tmp/imagecachedir --root /tmp/podman_test2968516396/root --runroot /tmp/podman_test2968516396/runroot --runtime crun --conmon /usr/bin/conmon --network-config-dir /tmp/podman_test2968516396/root/etc/networks --network-backend netavark --cgroup-manager systemd --tmpdir /tmp/podman_test2968516396 --events-backend file --db-backend sqlite --storage-driver vfs restart -t 2 test1 +[+0271s] time="2023-04-17T10:00:31-05:00" level=warning msg="StopSignal SIGTERM failed to stop container test1 in 2 seconds, resorting to SIGKILL" +[+0271s] test1 +[+0271s] < Exit [It] podman restart non-stop container with short timeout - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:148 @ 04/17/23 10:00:31.334 (2.681s) +[+0271s] > Enter [AfterEach] Podman restart - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:30 @ 04/17/23 10:00:31.334 +[+0271s] Running: /var/tmp/go/src/github.com/containers/podman/bin/podman --storage-opt vfs.imagestore=/tmp/imagecachedir --root /tmp/podman_test2968516396/root --runroot /tmp/podman_test2968516396/runroot --runtime crun --conmon /usr/bin/conmon --network-config-dir /tmp/podman_test2968516396/root/etc/networks --network-backend netavark --cgroup-manager systemd --tmpdir /tmp/podman_test2968516396 --events-backend file --db-backend sqlite --storage-driver vfs stop --all -t 0 +[+0271s] 7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc +[+0271s] Running: /var/tmp/go/src/github.com/containers/podman/bin/podman --storage-opt vfs.imagestore=/tmp/imagecachedir --root /tmp/podman_test2968516396/root --runroot /tmp/podman_test2968516396/runroot --runtime crun --conmon /usr/bin/conmon --network-config-dir /tmp/podman_test2968516396/root/etc/networks --network-backend netavark --cgroup-manager systemd --tmpdir /tmp/podman_test2968516396 --events-backend file --db-backend sqlite --storage-driver vfs pod rm -fa -t 0 +[+0271s] Running: /var/tmp/go/src/github.com/containers/podman/bin/podman --storage-opt vfs.imagestore=/tmp/imagecachedir --root /tmp/podman_test2968516396/root --runroot /tmp/podman_test2968516396/runroot --runtime crun --conmon /usr/bin/conmon --network-config-dir /tmp/podman_test2968516396/root/etc/networks --network-backend netavark --cgroup-manager systemd --tmpdir /tmp/podman_test2968516396 --events-backend file --db-backend sqlite --storage-driver vfs rm -fa -t 0 +[+0271s] 7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc +[+0271s] < Exit [AfterEach] Podman restart - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:30 @ 04/17/23 10:00:31.979 (645ms) +[+0271s] << Timeline >>> -$SCRIPT_BASE/integration_test.sh |& ${TIMESTAMP} -[08:26:19] START - All [+xxxx] lines that follow are relative to right now. -[+0002s] GO111MODULE=on go build -mod=vendor -gcflags 'all=-trimpath=/var/tmp/go/src/github.com/containers/podman' -asmflags 'all=-trimpath=/var/tmp/go/src/github.com/containers/podman' -ldflags '-X github.com/containers/podman/libpod/define.gitCommit=40f5d8b1becd381c4e8283ed3940d09193e4fe06 -X github.com/containers/podman/libpod/define.buildInfo=1582809981 -X github.com/containers/podman/libpod/config._installPrefix=/usr/local -X github.com/containers/podman/libpod/config._etcDir=/etc -extldflags ""' -tags " selinux systemd exclude_graphdriver_devicemapper seccomp varlink" -o bin/podman github.com/containers/podman/cmd/podman -[+0103s] • +[05:47:08] START - All [+xxxx] lines that follow are relative to 2023-04-17T05:47:08. +[+0004s] CGO_ENABLED=1 GOOS=linux GOARCH=amd64 go build -ldflags '-X github.com/containers/podman/v4/libpod/define.gitCommit=074143b0fac7af72cd92048d27931a92fe745084 -X github.com/containers/podman/v4/libpod/define.buildInfo=1681728432 -X github.com/containers/podman/v4/libpod/config._installPrefix=/usr/local -X github.com/containers/podman/v4/libpod/config._etcDir=/usr/local/etc -X github.com/containers/podman/v4/pkg/systemd/quadlet._binDir=/usr/local/bin -X github.com/containers/common/pkg/config.additionalHelperBinariesDir= ' -tags " selinux systemd exclude_graphdriver_devicemapper seccomp" -o test/checkseccomp/checkseccomp ./test/checkseccomp +[+0006s] CGO_ENABLED=1 GOOS=linux GOARCH=amd64 go build -ldflags '-X github.com/containers/podman/v4/libpod/define.gitCommit=074143b0fac7af72cd92048d27931a92fe745084 -X github.com/containers/podman/v4/libpod/define.buildInfo=1681728434 -X github.com/containers/podman/v4/libpod/config._installPrefix=/usr/local -X github.com/containers/podman/v4/libpod/config._etcDir=/usr/local/etc -X github.com/containers/podman/v4/pkg/systemd/quadlet._binDir=/usr/local/bin -X github.com/containers/common/pkg/config.additionalHelperBinariesDir= ' -o test/goecho/goecho ./test/goecho + ./hack/install_catatonit.sh 
-[+0103s] Podman pod restart
-         
  podman pod restart single empty pod

-           /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_restart_test.go:41
-         [BeforeEach] Podman pod restart
-           /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_restart_test.go:18
-         [It] podman pod restart single empty pod
-           /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_restart_test.go:41
-         # quadlet -no-kmsg-log /tmp/podman_test620310100/generated with QUADLET_UNIT_DIRS=/tmp/podman_test620310100/quadlet
-         quadlet-generator[98757]: Error converting 'range.less-subnet.network', ignoring: cannot set more ranges than subnets
-         # podman [+0271s] • [3.327 seconds]
+         Podman restart
+         /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:14
+         
  podman restart non-stop container with short timeout

+           /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:148
+[+0271s]
+           Timeline >>
+
           → Enter [BeforeEach] Podman restart - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:21 @ 04/17/23 10:00:28.653
+           ← Exit  [BeforeEach] Podman restart - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:21 @ 04/17/23 10:00:28.653 (0s)
+
           → Enter [It] podman restart non-stop container with short timeout - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:148 @ 04/17/23 10:00:28.653
+           # podman [options] pod create --infra=false --share
-         4810be0cfbd42241e349dbe7d50fbc54405cd320a6637c65fd5323f34d64af89
-         # podman [options] run -d --name test1 --env STOPSIGNAL=SIGKILL quay.io/libpod/alpine:latest sleep 999
+           7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc
+           # podman [options] pod restart 4810be0cfbd42241e349dbe7d50fbc54405cd320a6637c65fd5323f34d64af89
-         Error: no containers in pod 4810be0cfbd42241e349dbe7d50fbc54405cd320a6637c65fd5323f34d64af89 have no dependencies, cannot start pod: no such container
-         output:
-         [AfterEach] Podman pod restart
-           /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_restart_test.go:28
-         # podman [options] restart -t 2 test1
+           time="2023-04-17T10:00:31-05:00" level=warning msg="StopSignal SIGTERM failed to stop container test1 in 2 seconds, resorting to SIGKILL"
+           test1
+           ← Exit  [It] podman restart non-stop container with short timeout - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:148 @ 04/17/23 10:00:31.334 (2.681s)
+
           → Enter [AfterEach] Podman restart - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:30 @ 04/17/23 10:00:31.334
+           # podman [options] pod rm -fa
-         4810be0cfbd42241e349dbe7d50fbc54405cd320a6637c65fd5323f34d64af89
-
-[+0104s] # podman-remote [options] stop --all -t 0
+           7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc
+           # podman [options] pod rm -fa
-         4810be0cfbd42241e349dbe7d50fbc54405cd320a6637c65fd5323f34d64af89 again
-
-
-[+0107s] •
-
-
-
-[+0523s] Podman play kube with build
-         
  --build should override image in store

-           /var/tmp/go/src/github.com/containers/podman/test/e2e/play_build_test.go:215
-
-
-[+0479s] •
-
-
-
-[+0479s] Podman pod rm
-         
  podman pod rm -a doesn't remove a running container

-           /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_rm_test.go:119
-
-
-[+1405s] •
-
-
-
-[+1405s] Podman run entrypoint
-         
  podman run entrypoint == [""]

-           /var/tmp/go/src/github.com/containers/podman/test/e2e/run_entrypoint_test.go:47
-
-
-[+0184s] S [SKIPPING] [3.086 seconds]
-[+1385s] S [SKIPPING] in Spec Setup (BeforeEach) [0.001 seconds]
-
-
-[+1512s] Summarizing 6 Failures:
-[+1512s]
-         [Fail] Podman play kube with build [It] --build should override image in store
-         /var/tmp/go/src/github.com/containers/podman/test/e2e/play_build_test.go:259
+--db-backend sqlite
+--storage-driver vfs">[options] pod rm -fa -t 0
+           # podman [options] rm -fa -t 0
+           7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc
+           ← Exit  [AfterEach] Podman restart - /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:30 @ 04/17/23 10:00:31.979 (645ms)
+
<< Timeline == simple python