3
# tests for logformatter
5
(our $ME = $0) =~ s|^.*/||;
12
use File::Temp qw(tempdir);
15
# To test links to source files
16
$ENV{CIRRUS_CHANGE_IN_REPO} = 'ceci-nest-pas-une-sha';19
# Read the test cases (see __END__ section below)
23
while (my $line = <DATA>) {26
if ($line =~ /^==\s+(.*)/) {27
push @tests, { name => $1, input => [], expect => [] };30
elsif ($line =~ /^<<</) {33
elsif ($line =~ /^>>>/) {36
elsif (@tests && $line) {37
# Handles trailing spaces in log, because we can't have actual ones.
38
$line =~ s/\&TRAILINGSPACE;/ /g;
39
push @{ $tests[-1]{$context} }, $line;43
plan tests => scalar(@tests);
45
my $tempdir = tempdir("logformatter-test.XXXXXX", TMPDIR => 1, CLEANUP => !$ENV{DEBUG});48
or die "$ME: Could not cd $tempdir: $!\n";
51
my $name = $t->{name};52
(my $fname = $name) =~ s/\s+/_/g;
54
open my $fh_out, '>', "$fname.txt"
55
or die "$ME: Cannot create $tempdir/$fname.txt: $!\n";
56
print { $fh_out } "$_\n" for @{$t->{input}};58
or die "$ME: Error writing $tempdir/$fname.txt: $!\n";
60
system("$FindBin::Bin/logformatter $fname <$fname.txt >/dev/null");61
open my $fh_in, '<', "$fname.log.html"
62
or die "$ME: Fatal: $fname: logformatter did not create .log.html\n";
64
while (my $line = <$fh_in>) {66
push @actual, $line if $line =~ / begin processed output / .. $line =~ / end processed output /;
70
# Strip off leading and trailing "<pre>"
71
shift @actual; pop @actual;
73
# For debugging: preserve expected results
75
open my $fh_out, '>', "$fname.expect";
76
print { $fh_out } "$_\n" for @{$t->{expect}};80
is_deeply \@actual, $t->{expect}, $name;94
ok 2 bye # skip no reason
96
# (from function `assert' in file ./helpers.bash, line 343,
97
# from function `expect_output' in file ./helpers.bash, line 370,
98
# in test file ./run.bats, line 786)
99
# $ /path/to/podman foo -bar
100
# time="2023-01-05T15:15:20Z" level=debug msg="this is debug"
101
# time="2023-01-05T15:15:20Z" level=warning msg="this is warning"
102
# #| FAIL: exit code is 123; expected 321
106
<span class='bats-passed'><a name='t--00001'>ok 1 hi</a></span>
107
<span class='bats-skipped'><a name='t--00002'>ok 2 bye # skip no reason</a></span>
108
<span class='bats-failed'><a name='t--00003'>not ok 3 fail</a></span>
109
<span class='bats-log'># (from function `assert' in file ./<a class="codelink" href="https://github.com/containers/podman/blob/ceci-nest-pas-une-sha/test/system/helpers.bash#L343">helpers.bash, line 343</a>,</span>
110
<span class='bats-log'># from function `expect_output' in file ./<a class="codelink" href="https://github.com/containers/podman/blob/ceci-nest-pas-une-sha/test/system/helpers.bash#L370">helpers.bash, line 370</a>,</span>
111
<span class='bats-log'># in test file ./<a class="codelink" href="https://github.com/containers/podman/blob/ceci-nest-pas-une-sha/test/system/run.bats#L786">run.bats, line 786</a>)</span>
112
<span class='bats-log'># $ <b><span title="/path/to/podman">podman</span> foo -bar</b></span>
113
<span class='bats-log'># time=<span class='log-debug'>"2023-01-05T15:15:20Z"</span> level=<span class='log-debug'>debug</span> msg=<span class='log-debug'>"this is debug"</span></span>
114
<span class='bats-log'># time=<span class='log-warning'>"2023-01-05T15:15:20Z"</span> level=<span class='log-warning'>warning</span> msg=<span class='log-warning'>"this is warning"</span></span>
115
<span class='bats-log-failblock'># #| FAIL: exit code is 123; expected 321</span>
116
<span class='bats-passed'><a name='t--00004'>ok 4 blah</a></span>
117
<hr/><span class='bats-summary'>Summary: <span class='bats-passed'>2 Passed</span>, <span class='bats-failed'>1 Failed</span>, <span class='bats-skipped'>1 Skipped</span>. Total tests: 4</span>
120
== bats with timestamps 2023-05-16
123
[+0000s] ok 1 this passes
124
[+0018s] not ok 2 this fails, and includes command timestamps
125
[+0018s] # (from function `die' in file test/system/helpers.bash, line 564,
126
[+0018s] # from function `run_podman' in file test/system/helpers.bash, line 245,
127
[+0018s] # in test file test/system/888-foo.bats, line 15)
128
[+0018s] # `run_podman image exists sdfsdfsdf' failed
129
[+0018s] # [07:46:52.641456481] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman rm -t 0 --all --force --ignore
130
[+0018s] # [07:46:52.688219032] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman ps --all --external --format {{.ID}} {{.Names}}131
[+0018s] # [07:46:52.747613611] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman images --all --format {{.Repository}}:{{.Tag}} {{.ID}}132
[+0018s] # [07:46:52.791987930] quay.io/libpod/testimage:20221018 f5a99120db64
133
[+0018s] # [07:46:52.801043907] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman container exists nonesuch
134
[+0018s] # [07:46:52.842840593] [ rc=1 (expected) ]
135
[+0018s] # [07:46:52.848492506] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman container exists nonesuch
136
[+0018s] # [07:46:52.893686015] [ rc=1 (expected) ]
137
[+0018s] # [07:47:02.910006230] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman image inspect --format {{.Os}} quay.io/libpod/testimage:20221018138
[+0018s] # [07:47:02.961306244] linux
139
[+0018s] # [07:47:02.966100272] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman run --rm quay.io/libpod/testimage:20221018 sleep 7
140
[+0018s] # [07:47:10.167561880] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman image exists sdfsdfsdf
141
[+0018s] # [07:47:10.213750267] [ rc=1 (** EXPECTED 0 **) ]
142
[+0018s] # #/vvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvv
143
[+0018s] # #| FAIL: exit code is 1; expected 0
144
[+0018s] # #\^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^
145
[+0018s] # # [teardown]
146
[+0018s] # [07:47:10.219704348] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman pod rm -t 0 --all --force --ignore
147
[+0018s] # [07:47:10.263332041] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman rm -t 0 --all --force --ignore
148
[+0018s] # [07:47:10.300645440] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman network prune --force
149
[+0018s] # [07:47:10.337464840] $ /home/esm/src/atomic/2018-02.podman/libpod/bin/podman volume rm -a -f
151
<span class="timestamp">[+0000s] </span>1..2
152
<span class="timestamp"> </span><span class='bats-passed'><a name='t--00001'>ok 1 this passes</a></span>
153
<span class="timestamp">[+0018s] </span><span class='bats-failed'><a name='t--00002'>not ok 2 this fails, and includes command timestamps</a></span>
154
<span class="timestamp"> </span><span class='bats-log'># (from function `die' in file test/system/<a class="codelink" href="https://github.com/containers/podman/blob/ceci-nest-pas-une-sha/test/system/helpers.bash#L564">helpers.bash, line 564</a>,</span>
155
<span class="timestamp"> </span><span class='bats-log'># from function `run_podman' in file test/system/<a class="codelink" href="https://github.com/containers/podman/blob/ceci-nest-pas-une-sha/test/system/helpers.bash#L245">helpers.bash, line 245</a>,</span>
156
<span class="timestamp"> </span><span class='bats-log'># in test file test/system/<a class="codelink" href="https://github.com/containers/podman/blob/ceci-nest-pas-une-sha/test/system/888-foo.bats#L15">888-foo.bats, line 15</a>)</span>
157
<span class="timestamp"> </span><span class='bats-log'># `run_podman image exists sdfsdfsdf' failed</span>
158
<span class="timestamp"><span title="07:46:52.641456481"><+ ></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> rm -t 0 --all --force --ignore</b></span>
159
<span class="timestamp"><span title="07:46:52.688219032"><+046ms></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> ps --all --external --format {{.ID}} {{.Names}}</b></span>160
<span class="timestamp"><span title="07:46:52.747613611"><+059ms></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> images --all --format {{.Repository}}:{{.Tag}} {{.ID}}</b></span>161
<span class="timestamp"><span title="07:46:52.791987930"><+044ms></span> </span><span class='bats-log'># quay.io/libpod/testimage:20221018 f5a99120db64</span>
162
<span class="timestamp"><span title="07:46:52.801043907"><+009ms></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> container exists nonesuch</b></span>
163
<span class="timestamp"><span title="07:46:52.842840593"><+041ms></span> </span><span class='bats-log'># [ rc=1 (expected) ]</span>
164
<span class="timestamp"><span title="07:46:52.848492506"><+005ms></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> container exists nonesuch</b></span>
165
<span class="timestamp"><span title="07:46:52.893686015"><+045ms></span> </span><span class='bats-log'># [ rc=1 (expected) ]</span>
166
<span class="timestamp"><span title="07:47:02.910006230"><+0010s></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> image inspect --format {{.Os}} quay.io/libpod/testimage:20221018</b></span>167
<span class="timestamp"><span title="07:47:02.961306244"><+051ms></span> </span><span class='bats-log'># linux</span>
168
<span class="timestamp"><span title="07:47:02.966100272"><+004ms></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> run --rm quay.io/libpod/testimage:20221018 sleep 7</b></span>
169
<span class="timestamp"><span title="07:47:10.167561880"><+7.20s></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> image exists sdfsdfsdf</b></span>
170
<span class="timestamp"><span title="07:47:10.213750267"><+046ms></span> </span><span class='bats-log'># [ rc=1 (** EXPECTED 0 **) ]</span>
171
<span class="timestamp"> </span><span class='bats-log-failblock'># #/vvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvvv</span>
172
<span class="timestamp"> </span><span class='bats-log-failblock'># #| FAIL: exit code is 1; expected 0</span>
173
<span class="timestamp"> </span><span class='bats-log-failblock'># #\^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^</span>
174
<span class="timestamp"> </span><span class='bats-log'># # [teardown]</span>
175
<span class="timestamp"><span title="07:47:10.219704348"><+005ms></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> pod rm -t 0 --all --force --ignore</b></span>
176
<span class="timestamp"><span title="07:47:10.263332041"><+043ms></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> rm -t 0 --all --force --ignore</b></span>
177
<span class="timestamp"><span title="07:47:10.300645440"><+037ms></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> network prune --force</b></span>
178
<span class="timestamp"><span title="07:47:10.337464840"><+036ms></span> </span><span class='bats-log'># $ <b><span title="/home/esm/src/atomic/2018-02.podman/libpod/bin/podman">podman</span> volume rm -a -f</b></span>
179
<hr/><span class='bats-summary'>Summary: <span class='bats-passed'>1 Passed</span>, <span class='bats-failed'>1 Failed</span>. Total tests: 2</span>
184
[05:47:08] START - All [+xxxx] lines that follow are relative to 2023-04-17T05:47:08.
185
[+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
186
[+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
187
[+0006s] ./hack/install_catatonit.sh
188
[+0270s] ------------------------------
189
[+0271s] • [3.327 seconds]
190
[+0271s] Podman restart
191
[+0271s] /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:14
192
[+0271s] podman restart non-stop container with short timeout
193
[+0271s] /var/tmp/go/src/github.com/containers/podman/test/e2e/restart_test.go:148
194
[+0271s]&TRAILINGSPACE;
196
[+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
197
[+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)
198
[+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
199
[+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
200
[+0271s] 7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc
201
[+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
202
[+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"
204
[+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)
205
[+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
206
[+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
207
[+0271s] 7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc
208
[+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
209
[+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
210
[+0271s] 7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc
211
[+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)
213
[+0296s] ------------------------------
214
[+0298s] • [FAILED] [6.071 seconds]
215
[+0298s] TOP-LEVEL [AfterEach]
216
[+0298s] /var/tmp/go/src/github.com/containers/podman/test/e2e/common_test.go:117
217
[+0298s] Podman pod create
218
[+0298s] /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_infra_container_test.go:12
219
[+0298s] podman pod correctly sets up PIDNS
220
[+0298s] /var/tmp/go/src/github.com/containers/podman/test/e2e/pod_infra_container_test.go:154
221
[+0298s]&TRAILINGSPACE;&TRAILINGSPACE;&TRAILINGSPACE;
224
[+1741s] ------------------------------
225
[+1741s]&TRAILINGSPACE;
226
[+1741s] Summarizing 1 Failure:
227
[+1741s] [FAIL] TOP-LEVEL [AfterEach] Podman pod create podman pod correctly sets up PIDNS
228
[+1741s] /var/tmp/go/src/github.com/containers/podman/test/e2e/common_test.go:657
229
[+1741s]&TRAILINGSPACE;
230
[+1741s] Ran 1889 of 2014 Specs in 1607.919 seconds
231
[+1741s] FAIL! -- 1881 Passed | 1 Failed | 0 Pending | 125 Skipped
233
[05:47:08] START - All [+xxxx] lines that follow are relative to 2023-04-17T05:47:08.
234
<span class="timestamp">[+0004s] </span>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
235
<span class="timestamp">[+0006s] </span>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
236
<span class="timestamp"> </span>./hack/install_catatonit.sh
240
<span class="timestamp">[+0271s] </span>• <b>[3.327 seconds]</b>
241
<span class="timestamp"> </span><a name='t--Podman-restart--1'><h2 class="log-passed">Podman restart</h2></a>
242
<span class="timestamp"> </span>/var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/restart_test.go#L14'>/containers/podman/test/e2e/restart_test.go:14</a>
243
<span class="timestamp"> </span><a name='t--Podman-restart-podman-restart-non-stop-container-with-short-timeout--1'><h2 class="log-passed"> podman restart non-stop container with short timeout</h2></a>
244
<span class="timestamp"> </span> /var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/restart_test.go#L148'>/containers/podman/test/e2e/restart_test.go:148</a>
245
<span class="timestamp"> </span>
246
<span class="timestamp"> </span> Timeline >>
247
<div class="ginkgo-timeline ginkgo-beforeeach"><span class="timestamp"> </span> → Enter [<b>BeforeEach</b>] Podman restart - /var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/restart_test.go#L21'>/containers/podman/test/e2e/restart_test.go:21</a> @ 04/17/23 10:00:28.653
248
<span class="timestamp"> </span> ← Exit [BeforeEach] Podman restart - /var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/restart_test.go#L21'>/containers/podman/test/e2e/restart_test.go:21</a> @ 04/17/23 10:00:28.653 (0s)
249
</div><div class="ginkgo-timeline ginkgo-it"><span class="timestamp"> </span> → Enter [<b>It</b>] podman restart non-stop container with short timeout - /var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/restart_test.go#L148'>/containers/podman/test/e2e/restart_test.go:148</a> @ 04/17/23 10:00:28.653
250
<span class="timestamp"> </span><span class="boring"> #</span> <span title="/var/tmp/go/src/github.com/containers/podman/bin/podman"><b>podman</b></span> <span class="boring" title="--storage-opt vfs.imagestore=/tmp/imagecachedir
251
--root /tmp/podman_test2968516396/root
252
--runroot /tmp/podman_test2968516396/runroot
254
--conmon /usr/bin/conmon
255
--network-config-dir /tmp/podman_test2968516396/root/etc/networks
256
--network-backend netavark
257
--cgroup-manager systemd
258
--tmpdir /tmp/podman_test2968516396
261
--storage-driver vfs">[options]</span><b> run -d --name test1 --env STOPSIGNAL=SIGKILL quay.io/libpod/alpine:latest sleep 999</b>
262
<span class="timestamp"> </span> 7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc
263
<span class="timestamp"> </span><span class="boring"> #</span> <span title="/var/tmp/go/src/github.com/containers/podman/bin/podman"><b>podman</b></span> <span class="boring" title="--storage-opt vfs.imagestore=/tmp/imagecachedir
264
--root /tmp/podman_test2968516396/root
265
--runroot /tmp/podman_test2968516396/runroot
267
--conmon /usr/bin/conmon
268
--network-config-dir /tmp/podman_test2968516396/root/etc/networks
269
--network-backend netavark
270
--cgroup-manager systemd
271
--tmpdir /tmp/podman_test2968516396
274
--storage-driver vfs">[options]</span><b> restart -t 2 test1</b>
275
<span class="timestamp"> </span> time=<span class='log-warning'>"2023-04-17T10:00:31-05:00"</span> level=<span class='log-warning'>warning</span> msg=<span class='log-warning'>"StopSignal SIGTERM failed to stop container test1 in 2 seconds, resorting to SIGKILL"</span>
276
<span class="timestamp"> </span> test1
277
<span class="timestamp"> </span> ← Exit [It] podman restart non-stop container with short timeout - /var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/restart_test.go#L148'>/containers/podman/test/e2e/restart_test.go:148</a> @ 04/17/23 10:00:31.334 (2.681s)
278
</div><div class="ginkgo-timeline ginkgo-aftereach"><span class="timestamp"> </span> → Enter [<b>AfterEach</b>] Podman restart - /var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/restart_test.go#L30'>/containers/podman/test/e2e/restart_test.go:30</a> @ 04/17/23 10:00:31.334
279
<span class="timestamp"> </span><span class="boring"> #</span> <span title="/var/tmp/go/src/github.com/containers/podman/bin/podman"><b>podman</b></span> <span class="boring" title="--storage-opt vfs.imagestore=/tmp/imagecachedir
280
--root /tmp/podman_test2968516396/root
281
--runroot /tmp/podman_test2968516396/runroot
283
--conmon /usr/bin/conmon
284
--network-config-dir /tmp/podman_test2968516396/root/etc/networks
285
--network-backend netavark
286
--cgroup-manager systemd
287
--tmpdir /tmp/podman_test2968516396
290
--storage-driver vfs">[options]</span><b> stop --all -t 0</b>
291
<span class="timestamp"> </span> 7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc
292
<span class="timestamp"> </span><span class="boring"> #</span> <span title="/var/tmp/go/src/github.com/containers/podman/bin/podman"><b>podman</b></span> <span class="boring" title="--storage-opt vfs.imagestore=/tmp/imagecachedir
293
--root /tmp/podman_test2968516396/root
294
--runroot /tmp/podman_test2968516396/runroot
296
--conmon /usr/bin/conmon
297
--network-config-dir /tmp/podman_test2968516396/root/etc/networks
298
--network-backend netavark
299
--cgroup-manager systemd
300
--tmpdir /tmp/podman_test2968516396
303
--storage-driver vfs">[options]</span><b> pod rm -fa -t 0</b>
304
<span class="timestamp"> </span><span class="boring"> #</span> <span title="/var/tmp/go/src/github.com/containers/podman/bin/podman"><b>podman</b></span> <span class="boring" title="--storage-opt vfs.imagestore=/tmp/imagecachedir
305
--root /tmp/podman_test2968516396/root
306
--runroot /tmp/podman_test2968516396/runroot
308
--conmon /usr/bin/conmon
309
--network-config-dir /tmp/podman_test2968516396/root/etc/networks
310
--network-backend netavark
311
--cgroup-manager systemd
312
--tmpdir /tmp/podman_test2968516396
315
--storage-driver vfs">[options]</span><b> rm -fa -t 0</b>
316
<span class="timestamp"> </span> 7f5f8fb3d043984cdff65994d14c4fd157479d20e0a0fcf769c35b50e8975edc
317
<span class="timestamp"> </span> ← Exit [AfterEach] Podman restart - /var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/restart_test.go#L30'>/containers/podman/test/e2e/restart_test.go:30</a> @ 04/17/23 10:00:31.979 (645ms)
318
</div><span class="timestamp"> </span> << Timeline
322
<span class="timestamp">[+0298s] </span><span class="log-failed">• [FAILED] <b><span class='log-slow'>[6.071 seconds]</span></b></span>
323
<span class="timestamp"> </span><a name='t--TOP-LEVEL--AfterEach---1'><h2 class="log-failed">TOP-LEVEL [AfterEach]</h2></a>
324
<span class="timestamp"> </span>/var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/common_test.go#L117'>/containers/podman/test/e2e/common_test.go:117</a>
325
<span class="timestamp"> </span><a name='t--Podman-pod-create--1'><h2 class="log-failed"> Podman pod create</h2></a>
326
<span class="timestamp"> </span> /var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/pod_infra_container_test.go#L12'>/containers/podman/test/e2e/pod_infra_container_test.go:12</a>
327
<span class="timestamp"> </span><a name='t--Podman-pod-create-podman-pod-correctly-sets-up-PIDNS--1'><h2 class="log-failed"> podman pod correctly sets up PIDNS</h2></a>
328
<span class="timestamp"> </span> /var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/pod_infra_container_test.go#L154'>/containers/podman/test/e2e/pod_infra_container_test.go:154</a>
329
<span class="timestamp"> </span>&TRAILINGSPACE;&TRAILINGSPACE;
330
<span class="timestamp"> </span> Timeline >>
331
<span class="timestamp"> </span> << Timeline
335
<span class="timestamp">[+1741s] </span>
336
<span class="timestamp"> </span>Summarizing 1 Failure:
337
<span class="timestamp"> </span><span class="log-error"> [FAIL] TOP-LEVEL [AfterEach] <a href='#t--Podman-pod-create-podman-pod-correctly-sets-up-PIDNS--1'>Podman pod create podman pod correctly sets up PIDNS</a></span>
338
<span class="timestamp"> </span> /var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/074143b0fac7af72cd92048d27931a92fe745084/test/e2e/common_test.go#L657'>/containers/podman/test/e2e/common_test.go:657</a>
339
<span class="timestamp"> </span>
340
<span class="timestamp"> </span>Ran 1889 of 2014 Specs in 1607.919 seconds
341
<span class="timestamp"> </span><span class="ginkgo-final-fail">FAIL!</span> -- <span class="bats-passed"><b>1881</b> Passed</span> | <span class="bats-failed"><b>1</b> Failed</span> | 0 Pending | <span class="bats-skipped"><b>125</b> Skipped</span>
346
[+0234s] env CONTAINERS_CONF=/var/tmp/go/src/github.com/containers/podman/test/apiv2/containers.conf PODMAN=./bin/podman /usr/bin/python3 -m unittest discover -v ./test/python/docker
347
[+0238s] test_copy_to_container (compat.test_containers.TestContainers) ... /usr/lib/python3.10/site-packages/docker/utils/utils.py:269: DeprecationWarning: urllib.parse.splitnport() is deprecated as of 3.8, use urllib.parse.urlparse() instead
348
[+0238s] host, port = splitnport(parsed_url.netloc)
350
[+0243s] test_create_container (compat.test_containers.TestContainers) ... ok
351
[+0244s] test_create_network (compat.test_containers.TestContainers) ... ok
352
[+0245s] test_filters (compat.test_containers.TestContainers) ... skipped 'TODO Endpoint does not yet support filters'
353
[+0246s] test_kill_container (compat.test_containers.TestContainers) ... /usr/lib64/python3.10/threading.py:372: ResourceWarning: unclosed <socket.socket fd=4, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=6, laddr=('127.0.0.1', 55054), raddr=('127.0.0.1', 8080)>354
[+0246s] waiters_to_notify = _deque(_islice(all_waiters, n))
355
[+0246s] ResourceWarning: Enable tracemalloc to get the object allocation traceback
357
[+0248s] test_list_container (compat.test_containers.TestContainers) ... ok
358
[+0252s] test_mount_preexisting_dir (compat.test_containers.TestContainers) ... ok
359
[+0253s] test_mount_rw_by_default (compat.test_containers.TestContainers) ... ok
360
[+0257s] test_non_existent_workdir (compat.test_containers.TestContainers) ... ok
361
[+0258s] test_pause_container (compat.test_containers.TestContainers) ... ok
362
[+0260s] test_pause_stopped_container (compat.test_containers.TestContainers) ... ok
363
[+0261s] test_remove_container (compat.test_containers.TestContainers) ... ok
364
[+0262s] test_remove_container_without_force (compat.test_containers.TestContainers) ... /usr/lib64/python3.10/email/feedparser.py:89: ResourceWarning: unclosed <socket.socket fd=4, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=6, laddr=('127.0.0.1', 55068), raddr=('127.0.0.1', 8080)>365
[+0262s] for ateof in reversed(self._eofstack):
366
[+0262s] ResourceWarning: Enable tracemalloc to get the object allocation traceback
367
[+0262s] /usr/lib64/python3.10/email/feedparser.py:89: ResourceWarning: unclosed <socket.socket fd=5, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=6, laddr=('127.0.0.1', 55074), raddr=('127.0.0.1', 8080)>368
[+0262s] for ateof in reversed(self._eofstack):
369
[+0262s] ResourceWarning: Enable tracemalloc to get the object allocation traceback
371
[+0264s] test_restart_container (compat.test_containers.TestContainers) ... ok
372
[+0265s] test_start_container (compat.test_containers.TestContainers) ... ok
373
[+0267s] test_start_container_with_random_port_bind (compat.test_containers.TestContainers) ... ok
374
[+0268s] test_stop_container (compat.test_containers.TestContainers) ... ok
375
[+0269s] test_unpause_container (compat.test_containers.TestContainers) ... ok
376
[+0273s] test_build_image (compat.test_images.TestImages) ... ok
377
[+0273s] test_get_image_exists_not (compat.test_images.TestImages)
378
[+0274s] Negative test for get image ... ok
379
[+0274s] test_image_history (compat.test_images.TestImages)
380
[+0274s] Image history ... ok
381
[+0274s] test_list_images (compat.test_images.TestImages)
382
[+0276s] List images ... ok
383
[+0276s] test_load_corrupt_image (compat.test_images.TestImages)
384
[+0277s] Import|Load Image failure ... ok
385
[+0277s] test_load_image (compat.test_images.TestImages)
386
[+0279s] Import|Load Image ... ok
387
[+0279s] test_remove_image (compat.test_images.TestImages)
388
[+0280s] Remove image ... ok
389
[+0280s] test_retag_valid_image (compat.test_images.TestImages)
390
[+0280s] Validates if name updates when the image is retagged ... ok
391
[+0280s] test_save_image (compat.test_images.TestImages)
392
[+0282s] Export Image ... ok
393
[+0282s] test_search_bogus_image (compat.test_images.TestImages)
394
[+0290s] Search for bogus image should throw exception ... ok
395
[+0290s] test_search_image (compat.test_images.TestImages)
396
[+0291s] Search for image ... FAIL
397
[+0291s] test_tag_valid_image (compat.test_images.TestImages)
398
[+0292s] Validates if the image is tagged successfully ... ok
399
[+0296s] test_Info (compat.test_system.TestSystem) ... ok
400
[+0298s] test_info_container_details (compat.test_system.TestSystem) ... ok
401
[+0299s] test_version (compat.test_system.TestSystem) ... ok
402
[+0299s] ======================================================================
403
[+0299s] FAIL: test_search_image (compat.test_images.TestImages)
404
[+0299s] Search for image
405
[+0299s] ----------------------------------------------------------------------
406
[+0299s] Traceback (most recent call last):
407
[+0299s] File "/var/tmp/go/src/github.com/containers/podman/test/python/docker/compat/test_images.py", line 90, in test_search_image
408
[+0299s] self.assertIn("alpine", r["Name"])409
[+0299s] AssertionError: 'alpine' not found in 'docker.io/docker/desktop-kubernetes'
410
[+0299s] ----------------------------------------------------------------------
411
[+0299s] Ran 33 tests in 63.138s
412
[+0299s] FAILED (failures=1, skipped=1)
413
[+0299s] make: *** [Makefile:616: localapiv2] Error 1
415
<span class="timestamp">[+0234s] </span>env CONTAINERS_CONF=/var/tmp/go/src/github.com/containers/podman/test/apiv2/containers.conf PODMAN=./bin/podman /usr/bin/python3 -m unittest discover -v ./test/python/docker
416
<span class="timestamp">[+0238s] </span>test_copy_to_container (compat.test_containers.TestContainers) ... /usr/lib/python3.10/site-packages/docker/utils/utils.py:269: DeprecationWarning: urllib.parse.splitnport() is deprecated as of 3.8, use urllib.parse.urlparse() instead
417
<span class="timestamp"> </span> host, port = splitnport(parsed_url.netloc)
418
<span class="timestamp">[+0241s] </span>ok
419
<span class="timestamp">[+0243s] </span><span class='bats-passed'>test_create_container (compat.test_containers.TestContainers) ... ok</span>
420
<span class="timestamp">[+0244s] </span><span class='bats-passed'>test_create_network (compat.test_containers.TestContainers) ... ok</span>
421
<span class="timestamp">[+0245s] </span><span class='bats-skipped'>test_filters (compat.test_containers.TestContainers) ... skipped 'TODO Endpoint does not yet support filters'</span>
422
<span class="timestamp">[+0246s] </span>test_kill_container (compat.test_containers.TestContainers) ... /usr/lib64/python3.10/threading.py:372: ResourceWarning: unclosed <socket.socket fd=4, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=6, laddr=('127.0.0.1', 55054), raddr=('127.0.0.1', 8080)>
423
<span class="timestamp"> </span> waiters_to_notify = _deque(_islice(all_waiters, n))
424
<span class="timestamp"> </span>ResourceWarning: Enable tracemalloc to get the object allocation traceback
425
<span class="timestamp">[+0247s] </span>ok
426
<span class="timestamp">[+0248s] </span><span class='bats-passed'>test_list_container (compat.test_containers.TestContainers) ... ok</span>
427
<span class="timestamp">[+0252s] </span><span class='bats-passed'>test_mount_preexisting_dir (compat.test_containers.TestContainers) ... ok</span>
428
<span class="timestamp">[+0253s] </span><span class='bats-passed'>test_mount_rw_by_default (compat.test_containers.TestContainers) ... ok</span>
429
<span class="timestamp">[+0257s] </span><span class='bats-passed'>test_non_existent_workdir (compat.test_containers.TestContainers) ... ok</span>
430
<span class="timestamp">[+0258s] </span><span class='bats-passed'>test_pause_container (compat.test_containers.TestContainers) ... ok</span>
431
<span class="timestamp">[+0260s] </span><span class='bats-passed'>test_pause_stopped_container (compat.test_containers.TestContainers) ... ok</span>
432
<span class="timestamp">[+0261s] </span><span class='bats-passed'>test_remove_container (compat.test_containers.TestContainers) ... ok</span>
433
<span class="timestamp">[+0262s] </span>test_remove_container_without_force (compat.test_containers.TestContainers) ... /usr/lib64/python3.10/email/feedparser.py:89: ResourceWarning: unclosed <socket.socket fd=4, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=6, laddr=('127.0.0.1', 55068), raddr=('127.0.0.1', 8080)>
434
<span class="timestamp"> </span> for ateof in reversed(self._eofstack):
435
<span class="timestamp"> </span>ResourceWarning: Enable tracemalloc to get the object allocation traceback
436
<span class="timestamp"> </span>/usr/lib64/python3.10/email/feedparser.py:89: ResourceWarning: unclosed <socket.socket fd=5, family=AddressFamily.AF_INET, type=SocketKind.SOCK_STREAM, proto=6, laddr=('127.0.0.1', 55074), raddr=('127.0.0.1', 8080)>
437
<span class="timestamp"> </span> for ateof in reversed(self._eofstack):
438
<span class="timestamp"> </span>ResourceWarning: Enable tracemalloc to get the object allocation traceback
439
<span class="timestamp"> </span>ok
440
<span class="timestamp">[+0264s] </span><span class='bats-passed'>test_restart_container (compat.test_containers.TestContainers) ... ok</span>
441
<span class="timestamp">[+0265s] </span><span class='bats-passed'>test_start_container (compat.test_containers.TestContainers) ... ok</span>
442
<span class="timestamp">[+0267s] </span><span class='bats-passed'>test_start_container_with_random_port_bind (compat.test_containers.TestContainers) ... ok</span>
443
<span class="timestamp">[+0268s] </span><span class='bats-passed'>test_stop_container (compat.test_containers.TestContainers) ... ok</span>
444
<span class="timestamp">[+0269s] </span><span class='bats-passed'>test_unpause_container (compat.test_containers.TestContainers) ... ok</span>
445
<span class="timestamp">[+0273s] </span><span class='bats-passed'>test_build_image (compat.test_images.TestImages) ... ok</span>
446
<span class="timestamp"> </span>test_get_image_exists_not (compat.test_images.TestImages)
447
<span class="timestamp">[+0274s] </span><span class='bats-passed'>Negative test for get image ... ok</span>
448
<span class="timestamp"> </span>test_image_history (compat.test_images.TestImages)
449
<span class="timestamp"> </span><span class='bats-passed'>Image history ... ok</span>
450
<span class="timestamp"> </span>test_list_images (compat.test_images.TestImages)
451
<span class="timestamp">[+0276s] </span><span class='bats-passed'>List images ... ok</span>
452
<span class="timestamp"> </span>test_load_corrupt_image (compat.test_images.TestImages)
453
<span class="timestamp">[+0277s] </span><span class='bats-passed'>Import|Load Image failure ... ok</span>
454
<span class="timestamp"> </span>test_load_image (compat.test_images.TestImages)
455
<span class="timestamp">[+0279s] </span><span class='bats-passed'>Import|Load Image ... ok</span>
456
<span class="timestamp"> </span>test_remove_image (compat.test_images.TestImages)
457
<span class="timestamp">[+0280s] </span><span class='bats-passed'>Remove image ... ok</span>
458
<span class="timestamp"> </span>test_retag_valid_image (compat.test_images.TestImages)
459
<span class="timestamp"> </span><span class='bats-passed'>Validates if name updates when the image is retagged ... ok</span>
460
<span class="timestamp"> </span>test_save_image (compat.test_images.TestImages)
461
<span class="timestamp">[+0282s] </span><span class='bats-passed'>Export Image ... ok</span>
462
<span class="timestamp"> </span>test_search_bogus_image (compat.test_images.TestImages)
463
<span class="timestamp">[+0290s] </span><span class='bats-passed'>Search for bogus image should throw exception ... ok</span>
464
<span class="timestamp"> </span>test_search_image (compat.test_images.TestImages)
465
<span class="timestamp">[+0291s] </span><span class='bats-failed'>Search for image ... FAIL</span>
466
<span class="timestamp"> </span>test_tag_valid_image (compat.test_images.TestImages)
467
<span class="timestamp">[+0292s] </span><span class='bats-passed'>Validates if the image is tagged successfully ... ok</span>
468
<span class="timestamp">[+0296s] </span><span class='bats-passed'>test_Info (compat.test_system.TestSystem) ... ok</span>
469
<span class="timestamp">[+0298s] </span><span class='bats-passed'>test_info_container_details (compat.test_system.TestSystem) ... ok</span>
470
<span class="timestamp">[+0299s] </span><span class='bats-passed'>test_version (compat.test_system.TestSystem) ... ok</span>
471
<div class='log-error'>
472
<span class="timestamp"> </span>======================================================================
473
<span class="timestamp"> </span>FAIL: test_search_image (compat.test_images.TestImages)
474
<span class="timestamp"> </span>Search for image
475
<span class="timestamp"> </span>----------------------------------------------------------------------
476
<span class="timestamp"> </span>Traceback (most recent call last):
477
<span class="timestamp"> </span> File "/var/tmp/go/src/github.com<a class="codelink" href='https://github.com/containers/podman/blob/ceci-nest-pas-une-sha/test/python/docker/compat/test_images.py#L90'>/containers/podman/test/python/docker/compat/test_images.py", line 90</a>, in test_search_image
478
<span class="timestamp"> </span> self.assertIn("alpine", r["Name"])
479
<span class="timestamp"> </span>AssertionError: 'alpine' not found in 'docker.io/docker/desktop-kubernetes'
480
<span class="timestamp"> </span>----------------------------------------------------------------------
482
<span class="timestamp"> </span>Ran 33 tests in 63.138s
483
<span class="timestamp"> </span><span class='bats-failed'>FAILED (failures=1, skipped=1)</span>
484
<span class="timestamp"> </span>make: *** [Makefile:616: localapiv2] Error 1
485
<hr/><span class='bats-summary'>Summary: <span class='bats-passed'>28 Passed</span>, <span class='bats-failed'>1 Failed</span>, <span class='bats-skipped'>1 Skipped</span>. Total tests: 30 <span class='bats-failed'>(WARNING: expected 33)</span></span>