canonical-ubuntu-qa team mailing list archive

Thread
Date
[Bug 1970971] Re: ubuntu_ltp_controllers testcase memcontrol03 fails on impish/jammy

To: canonical-ubuntu-qa@xxxxxxxxxxxxxxxxxxx
From: Mehmet Basaran <1970971@xxxxxxxxxxxxxxxxxx>
Date: Thu, 29 Aug 2024 14:51:50 -0000
Reply-to: Bug 1970971 <1970971@xxxxxxxxxxxxxxxxxx>
Sender: noreply@xxxxxxxxxxxxx
Seen this for (cycle=2024.08.05) jammy:lowlatency-hwe-6.8
6.8.0-44.44.1~22.04.1 on metal:helo-kernel.arm64, riccioli.amd64

I haven't checked the test. My suspicion is that, we wait for a process
to finish (especially if it is a throughput oriented one where
lowlatency kernels perform worse) it doesn't finish in the preallocated
time slot and has to be killed via signal.

Here is the log:

06:49:24 INFO | 	START	ubuntu_ltp_controllers.memcontrol03	ubuntu_ltp_controllers.memcontrol03	timestamp=1724914164	timeout=4500	localtime=Aug 29 06:49:24	
06:49:24 DEBUG| Persistent state client._record_indent now set to 2
06:49:24 DEBUG| Persistent state client.unexpected_reboot now set to ('ubuntu_ltp_controllers.memcontrol03', 'ubuntu_ltp_controllers.memcontrol03')
06:49:24 DEBUG| Waiting for pid 126554 for 4500 seconds
06:49:24 WARNI| System python is too old, crash handling disabled
06:49:24 INFO | Set timeout multiplier LTP_TIMEOUT_MUL=5 for memcontrol03
06:49:41 ERROR| Exception escaping from test:
Traceback (most recent call last):
  File "/home/ubuntu/autotest/client/shared/test.py", line 411, in _exec
    _call_test_function(self.execute, *p_args, **p_dargs)
  File "/home/ubuntu/autotest/client/shared/test.py", line 823, in _call_test_function
    return func(*args, **dargs)
  File "/home/ubuntu/autotest/client/shared/test.py", line 291, in execute
    postprocess_profiled_run, args, dargs)
  File "/home/ubuntu/autotest/client/shared/test.py", line 212, in _call_run_once
    self.run_once(*args, **dargs)
  File "/home/ubuntu/autotest/client/tests/ubuntu_ltp_controllers/ubuntu_ltp_controllers.py", line 137, in run_once
    print(utils.system_output(cmd, verbose=False))
  File "/home/ubuntu/autotest/client/shared/utils.py", line 1271, in system_output
    verbose=verbose, args=args).stdout
  File "/home/ubuntu/autotest/client/shared/utils.py", line 918, in run
    "Command returned non-zero exit status")
CmdError: Command </opt/ltp/runltp -f /tmp/target -q -C /dev/null -l /dev/null -T /dev/null> failed, rc=1, Command returned non-zero exit status
* Command: 
    /opt/ltp/runltp -f /tmp/target -q -C /dev/null -l /dev/null -T /dev/null
Exit status: 1
Duration: 16.5242979527

stdout:
Checking for required user/group ids

'root' user id and group found.
'nobody' user id and group found.
'bin' user id and group found.
'daemon' user id and group found.
Users group found.
Sys group found.
Required users/groups exist.
no big block device was specified on commandline.
Tests which require a big block device are disabled.
You can specify it with option -z
INFO: Test start time: Thu Aug 29 06:49:24 UTC 2024
COMMAND:    /opt/ltp/bin/ltp-pan -q  -e -S   -a 126558     -n 126558  -f /tmp/ltp-WtLCJ4uAEW/alltests -l /dev/null  -C /dev/null -T /dev/null
LOG File: /dev/null
FAILED COMMAND File: /dev/null
TCONF COMMAND File: /dev/null
Running tests.......
tst_device.c:96: TINFO: Found free device 3 '/dev/loop3'
tst_test.c:1733: TINFO: LTP version: 20240524
tst_test.c:1617: TINFO: Timeout per run is 0h 02m 30s
tst_supported_fs_types.c:96: TINFO: Kernel supports ext2
tst_supported_fs_types.c:61: TINFO: mkfs.ext2 does exist
tst_supported_fs_types.c:96: TINFO: Kernel supports ext3
tst_supported_fs_types.c:61: TINFO: mkfs.ext3 does exist
tst_supported_fs_types.c:96: TINFO: Kernel supports ext4
tst_supported_fs_types.c:61: TINFO: mkfs.ext4 does exist
tst_supported_fs_types.c:96: TINFO: Kernel supports xfs
tst_supported_fs_types.c:61: TINFO: mkfs.xfs does exist
tst_supported_fs_types.c:96: TINFO: Kernel supports btrfs
tst_supported_fs_types.c:61: TINFO: mkfs.btrfs does exist
tst_supported_fs_types.c:96: TINFO: Kernel supports bcachefs
tst_supported_fs_types.c:57: TINFO: mkfs.bcachefs does not exist
tst_supported_fs_types.c:168: TINFO: Skipping vfat as requested by the test
tst_supported_fs_types.c:168: TINFO: Skipping exfat as requested by the test
tst_supported_fs_types.c:168: TINFO: Skipping tmpfs as requested by the test
tst_test.c:1693: TINFO: === Testing on ext2 ===
tst_test.c:1106: TINFO: Formatting /dev/loop3 with ext2 opts='' extra opts=''
mke2fs 1.46.5 (30-Dec-2021)
tst_test.c:1120: TINFO: Mounting /dev/loop3 to /tmp/ltp-WtLCJ4uAEW/LTP_memqICcPZ/mntdir fstyp=ext2 flags=0
memcontrol03.c:144: TINFO: Child 126630 in leaf_C: Allocating pagecache: 52428800
memcontrol03.c:144: TINFO: Child 126646 in leaf_D: Allocating pagecache: 52428800
memcontrol03.c:144: TINFO: Child 126648 in leaf_F: Allocating pagecache: 52428800
memcontrol03.c:107: TINFO: Child 126650 in trunk_G: Allocating anon: 155189248
memcontrol03.c:125: TFAIL: Expected child 126650 to exit(0), but instead killed by SIGKILL
memcontrol03.c:208: TPASS: Expect: (A/B memory.current=54591488) ~= 52428800
memcontrol03.c:214: TPASS: Expect: (A/B/C memory.current=25808896) ~= 34603008
memcontrol03.c:216: TPASS: Expect: (A/B/D memory.current=25763840) ~= 17825792
memcontrol03.c:218: TPASS: Expect: (A/B/E memory.current=0) ~= 0
memcontrol03.c:107: TINFO: Child 126651 in trunk_G: Allocating anon: 178257920
memcontrol03.c:116: TPASS: Child 126651 killed by OOM
memcontrol03.c:224: TPASS: Expect: (A/B memory.current=54476800) ~= 52428800
tst_test.c:1693: TINFO: === Testing on ext3 ===
tst_test.c:1106: TINFO: Formatting /dev/loop3 with ext3 opts='' extra opts=''
mke2fs 1.46.5 (30-Dec-2021)
tst_test.c:1120: TINFO: Mounting /dev/loop3 to /tmp/ltp-WtLCJ4uAEW/LTP_memqICcPZ/mntdir fstyp=ext3 flags=0
memcontrol03.c:144: TINFO: Child 126659 in leaf_C: Allocating pagecache: 52428800
memcontrol03.c:144: TINFO: Child 126661 in leaf_D: Allocating pagecache: 52428800
memcontrol03.c:144: TINFO: Child 126662 in leaf_F: Allocating pagecache: 52428800
memcontrol03.c:107: TINFO: Child 126664 in trunk_G: Allocating anon: 155189248
memcontrol03.c:125: TFAIL: Expected child 126664 to exit(0), but instead killed by SIGKILL
memcontrol03.c:208: TPASS: Expect: (A/B memory.current=54870016) ~= 52428800
memcontrol03.c:214: TPASS: Expect: (A/B/C memory.current=26083328) ~= 34603008
memcontrol03.c:216: TPASS: Expect: (A/B/D memory.current=25763840) ~= 17825792
memcontrol03.c:218: TPASS: Expect: (A/B/E memory.current=0) ~= 0
memcontrol03.c:107: TINFO: Child 126665 in trunk_G: Allocating anon: 178257920
memcontrol03.c:116: TPASS: Child 126665 killed by OOM
memcontrol03.c:224: TPASS: Expect: (A/B memory.current=54751232) ~= 52428800
tst_test.c:1693: TINFO: === Testing on ext4 ===
tst_test.c:1106: TINFO: Formatting /dev/loop3 with ext4 opts='' extra opts=''
mke2fs 1.46.5 (30-Dec-2021)
tst_test.c:1120: TINFO: Mounting /dev/loop3 to /tmp/ltp-WtLCJ4uAEW/LTP_memqICcPZ/mntdir fstyp=ext4 flags=0
memcontrol03.c:144: TINFO: Child 126674 in leaf_C: Allocating pagecache: 52428800
memcontrol03.c:144: TINFO: Child 126676 in leaf_D: Allocating pagecache: 52428800
memcontrol03.c:144: TINFO: Child 126677 in leaf_F: Allocating pagecache: 52428800
memcontrol03.c:107: TINFO: Child 126678 in trunk_G: Allocating anon: 155189248
memcontrol03.c:125: TFAIL: Expected child 126678 to exit(0), but instead killed by SIGKILL
memcontrol03.c:208: TPASS: Expect: (A/B memory.current=54972416) ~= 52428800
memcontrol03.c:214: TPASS: Expect: (A/B/C memory.current=26017792) ~= 34603008
memcontrol03.c:216: TPASS: Expect: (A/B/D memory.current=25985024) ~= 17825792
memcontrol03.c:218: TPASS: Expect: (A/B/E memory.current=0) ~= 0
memcontrol03.c:107: TINFO: Child 126679 in trunk_G: Allocating anon: 178257920
memcontrol03.c:116: TPASS: Child 126679 killed by OOM
memcontrol03.c:224: TPASS: Expect: (A/B memory.current=54849536) ~= 52428800
tst_test.c:1693: TINFO: === Testing on xfs ===
tst_test.c:1106: TINFO: Formatting /dev/loop3 with xfs opts='' extra opts=''
tst_test.c:1120: TINFO: Mounting /dev/loop3 to /tmp/ltp-WtLCJ4uAEW/LTP_memqICcPZ/mntdir fstyp=xfs flags=0
memcontrol03.c:144: TINFO: Child 126694 in leaf_C: Allocating pagecache: 52428800
memcontrol03.c:144: TINFO: Child 126695 in leaf_D: Allocating pagecache: 52428800
memcontrol03.c:144: TINFO: Child 126696 in leaf_F: Allocating pagecache: 52428800
memcontrol03.c:107: TINFO: Child 126697 in trunk_G: Allocating anon: 155189248
memcontrol03.c:125: TFAIL: Expected child 126697 to exit(0), but instead killed by SIGKILL
memcontrol03.c:208: TPASS: Expect: (A/B memory.current=55128064) ~= 52428800
memcontrol03.c:214: TPASS: Expect: (A/B/C memory.current=26079232) ~= 34603008
memcontrol03.c:216: TPASS: Expect: (A/B/D memory.current=26079232) ~= 17825792
memcontrol03.c:218: TPASS: Expect: (A/B/E memory.current=0) ~= 0
memcontrol03.c:107: TINFO: Child 126698 in trunk_G: Allocating anon: 178257920
memcontrol03.c:116: TPASS: Child 126698 killed by OOM
memcontrol03.c:224: TPASS: Expect: (A/B memory.current=55009280) ~= 52428800
tst_test.c:1693: TINFO: === Testing on btrfs ===
tst_test.c:1106: TINFO: Formatting /dev/loop3 with btrfs opts='' extra opts=''
tst_test.c:1120: TINFO: Mounting /dev/loop3 to /tmp/ltp-WtLCJ4uAEW/LTP_memqICcPZ/mntdir fstyp=btrfs flags=0
memcontrol03.c:144: TINFO: Child 126724 in leaf_C: Allocating pagecache: 52428800
memcontrol03.c:144: TINFO: Child 126729 in leaf_D: Allocating pagecache: 52428800
memcontrol03.c:144: TINFO: Child 126730 in leaf_F: Allocating pagecache: 52428800
memcontrol03.c:107: TINFO: Child 126731 in trunk_G: Allocating anon: 155189248
memcontrol03.c:125: TFAIL: Expected child 126731 to exit(0), but instead killed by SIGKILL
memcontrol03.c:208: TPASS: Expect: (A/B memory.current=55377920) ~= 52428800
memcontrol03.c:214: TPASS: Expect: (A/B/C memory.current=26198016) ~= 34603008
memcontrol03.c:216: TPASS: Expect: (A/B/D memory.current=26193920) ~= 17825792
memcontrol03.c:218: TPASS: Expect: (A/B/E memory.current=0) ~= 0
memcontrol03.c:107: TINFO: Child 126732 in trunk_G: Allocating anon: 178257920
memcontrol03.c:116: TPASS: Child 126732 killed by OOM
memcontrol03.c:224: TPASS: Expect: (A/B memory.current=55263232) ~= 52428800

Summary:
passed   30
failed   5
broken   0
skipped  0
warnings 0
INFO: ltp-pan reported some tests FAIL
LTP Version: 20240524
INFO: Test end time: Thu Aug 29 06:49:41 UTC 2024
06:49:41 INFO | Restore timeout multiplier LTP_TIMEOUT_MUL back to default
06:49:41 ERROR| child process failed
06:49:41 DEBUG| Traceback (most recent call last):
06:49:41 DEBUG|   File "/home/ubuntu/autotest/client/parallel.py", line 25, in fork_start
06:49:41 DEBUG|     l()
06:49:41 DEBUG|   File "/home/ubuntu/autotest/client/job.py", line 505, in <lambda>
06:49:41 DEBUG|     l = lambda: test.runtest(self, url, tag, args, dargs)
06:49:41 DEBUG|   File "/home/ubuntu/autotest/client/test.py", line 125, in runtest
06:49:41 DEBUG|     job.sysinfo.log_after_each_iteration)
06:49:41 DEBUG|   File "/home/ubuntu/autotest/client/shared/test.py", line 913, in runtest
06:49:41 DEBUG|     mytest._exec(args, dargs)
06:49:41 DEBUG|   File "/home/ubuntu/autotest/client/shared/test.py", line 411, in _exec
06:49:41 DEBUG|     _call_test_function(self.execute, *p_args, **p_dargs)
06:49:41 DEBUG|   File "/home/ubuntu/autotest/client/shared/test.py", line 823, in _call_test_function
06:49:41 DEBUG|     return func(*args, **dargs)
06:49:41 DEBUG|   File "/home/ubuntu/autotest/client/shared/test.py", line 291, in execute
06:49:41 DEBUG|     postprocess_profiled_run, args, dargs)
06:49:41 DEBUG|   File "/home/ubuntu/autotest/client/shared/test.py", line 212, in _call_run_once
06:49:41 DEBUG|     self.run_once(*args, **dargs)
06:49:41 DEBUG|   File "/home/ubuntu/autotest/client/tests/ubuntu_ltp_controllers/ubuntu_ltp_controllers.py", line 137, in run_once
06:49:41 DEBUG|     print(utils.system_output(cmd, verbose=False))
06:49:41 DEBUG|   File "/home/ubuntu/autotest/client/shared/utils.py", line 1271, in system_output
06:49:41 DEBUG|     verbose=verbose, args=args).stdout
06:49:41 DEBUG|   File "/home/ubuntu/autotest/client/shared/utils.py", line 918, in run
06:49:41 DEBUG|     "Command returned non-zero exit status")
06:49:41 DEBUG| CmdError: Command </opt/ltp/runltp -f /tmp/target -q -C /dev/null -l /dev/null -T /dev/null> failed, rc=1, Command returned non-zero exit status
06:49:41 DEBUG| * Command: 
06:49:41 DEBUG|     /opt/ltp/runltp -f /tmp/target -q -C /dev/null -l /dev/null -T /dev/null
06:49:41 DEBUG| Exit status: 1
06:49:41 DEBUG| Duration: 16.5242979527
06:49:41 DEBUG| 
06:49:41 DEBUG| stdout:
06:49:41 DEBUG| Checking for required user/group ids

-- 
You received this bug notification because you are a member of Canonical
Platform QA Team, which is subscribed to ubuntu-kernel-tests.
https://bugs.launchpad.net/bugs/1970971

Title:
  ubuntu_ltp_controllers testcase memcontrol03 fails on impish/jammy

Status in ubuntu-kernel-tests:
  New

Bug description:
  The testcase memcontrol03 from ubuntu_ltp_controllers is failing on
  ext3 with the 5.13 and 5.15 kernels with the following error:

  tst_test.c:1528: TINFO: Testing on ext3
  tst_test.c:996: TINFO: Formatting /dev/loop3 with ext3 opts='' extra opts=''
  mke2fs 1.46.3 (27-Jul-2021)
  [...]
  memcontrol03.c:125: TFAIL: Expected child 163441 to exit(0), but instead killed by SIGKILL

  This is not a regression as it has been failing before.

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu-kernel-tests/+bug/1970971/+subscriptions