<div dir="ltr"><div dir="ltr"><div class="gmail_default" style="font-size:small"><br></div></div><br><div class="gmail_quote"><div dir="ltr" class="gmail_attr">On Thu, Oct 31, 2019 at 1:41 AM Jan Stancek <<a href="mailto:jstancek@redhat.com" target="_blank">jstancek@redhat.com</a>> wrote:<br></div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex"><br>
<br>
----- Original Message -----<br>
> Hi Jan,<br>
> <br>
> > > I'm seeing similar failure:<br>
> <br>
> > > tst_kconfig.c:62: INFO: Parsing kernel config<br>
> > > '/boot/config-3.10.0-1106.el7.cki.s390x'<br>
> > > tst_test.c:1118: INFO: Timeout per run is 0h 05m 00s<br>
> > > tst_kconfig.c:62: INFO: Parsing kernel config<br>
> > > '/boot/config-3.10.0-1106.el7.cki.s390x'<br>
> > > acct02.c:239: INFO: Verifying using 'struct acct_v3'<br>
> > > acct02.c:192: INFO: == entry 1 ==<br>
> > > acct02.c:88: INFO: ac_btime < 1572367954 (1572367953)<br>
> <br>
> > ^^ This is 'acct02_helper', which seemingly started before 'start_time'.<br>
> <br>
> > ac_btime is calculated back from current time and elapsed time at<br>
> > fill_ac():<br>
> <br>
> >   ac->ac_btime = get_seconds() - elapsed;<br>
> <br>
> > s390s are slower, so my guess is another second ticks before btime gets<br>
> > calculated,<br>
> > and it appears to have started later.<br>
> Agree.<br>
> <br>
> > > acct02.c:192: INFO: == entry 2 ==<br>
> > > acct02.c:82: INFO: ac_comm != 'acct02_helper' ('acct02')<br>
> > > acct02.c:88: INFO: ac_btime < 1572367954 (1572367953)<br>
> > > acct02.c:133: INFO: ac_exitcode != 32768 (0)<br>
> > > acct02.c:141: INFO: ac_ppid != 25608 (25607)<br>
> <br>
> > The 2nd record is filled at acct(NULL), even though process still runs:<br>
> <br>
> > [ 1156.025654] CPU: 0 PID: 11772 Comm: acct02_helper Tainted: G<br>
> > OE  ------------   3.10.0-1106.el7.s390x #1<br>
> > <><br>
> > [ 1156.027105]  [<00000000001dd630>] fill_ac+0x0/0x440<br>
> > [ 1156.027108] ([<00000000001ddae8>] do_acct_process+0x78/0x1a0)<br>
> > [ 1156.027110]  [<00000000001de3ae>] acct_process+0xf6/0x140<br>
> > [ 1156.027112]  [<000000000014de32>] do_exit+0x752/0xa38<br>
> > [ 1156.027116]  [<000000000014e216>] do_group_exit+0x66/0xf8<br>
> > [ 1156.027118]  [<000000000014e2ea>] SyS_exit_group+0x42/0x48<br>
> <br>
> > [ 1157.033294] CPU: 1 PID: 11771 Comm: acct02 Tainted: G           OE<br>
> > ------------   3.10.0-1106.el7.s390x #1<br>
> > <><br>
> > [ 1157.033384]  [<00000000001dd630>] fill_ac+0x0/0x440<br>
> > [ 1157.033386] ([<00000000001ddae8>] do_acct_process+0x78/0x1a0)<br>
> > [ 1157.033389]  [<00000000001ddcda>] acct_pin_kill+0x52/0xa8<br>
> > [ 1157.033391]  [<000000000034a83e>] pin_kill+0xb6/0x178<br>
> > [ 1157.033395]  [<00000000001ddfe8>] SyS_acct+0x2b8/0x350<br>
> <br>
> Hm, did you reproduce it on 4.18 (rhel8)?<br>
<br>
Above was RHEL7. Do you expect different outcome on RHEL8?<br>
I was looking at upstream sources too and it looked similar.<br>
<br>
Anyway, I don't think this part is an issue, test will skip it<br>
because "comm" doesn't match.<br>
<br>
We can tweak 'ac_btime' condition, but I think the test will remain<br></blockquote><div><br></div><div class="gmail_default" style="font-size:small">How much time of the ac_btime shaking to be tolerated is proper?</div><div class="gmail_default" style="font-size:small"></div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">
race-y in environments with higher steal time:<br></blockquote><div><br></div><div><div class="gmail_default" style="font-size:small">I'm sorry, what does it mean here?</div><br></div><div><div class="gmail_default" style="font-size:small"></div></div><div> </div><blockquote class="gmail_quote" style="margin:0px 0px 0px 0.8ex;border-left:1px solid rgb(204,204,204);padding-left:1ex">
<br>
acct02.c:239: INFO: Verifying using 'struct acct_v3'<br>
acct02.c:192: INFO: == entry 1 ==<br>
acct02.c:127: INFO: elap_time/clock_ticks >= 2 (236/100: 2.00)<br>
acct02.c:192: INFO: == entry 2 ==<br>
acct02.c:82: INFO: ac_comm != 'acct02_helper' ('acct02')<br>
acct02.c:127: INFO: elap_time/clock_ticks >= 2 (236/100: 2.00)<br>
acct02.c:133: INFO: ac_exitcode != 32768 (0)<br>
acct02.c:141: INFO: ac_ppid != 43213 (43212)<br>
acct02.c:182: FAIL: end of file reached<br>
<br>
<br>
-- <br>
Mailing list info: <a href="https://lists.linux.it/listinfo/ltp" rel="noreferrer" target="_blank">https://lists.linux.it/listinfo/ltp</a><br>
</blockquote></div><br clear="all"><div><br></div>-- <br><div dir="ltr"><div dir="ltr"><div>Regards,<br></div><div>Li Wang<br></div></div></div></div>