impish/linux-aws: ubuntu_ltp_syscalls.perf_event_open02 test failure

81727 04:16:04 INFO | START ubuntu_ltp_syscalls.perf_event_open02 ubuntu_ltp_syscalls.perf_event_open02 timestamp=1632284164 timeout=900 localtime=Sep 22 04:16:04
      81728 04:16:04 DEBUG| Persistent state client._record_indent now set to 2
      81729 04:16:04 DEBUG| Persistent state client.unexpected_reboot now set to ('ubuntu_ltp_syscalls.perf_event_open02', 'ubuntu_ltp_syscalls.perf_event_open02')
      81730 04:16:04 DEBUG| Waiting for pid 860291 for 900 seconds
      81731 04:16:05 WARNI| System python is too old, crash handling disabled
      81732 04:16:14 ERROR| Exception escaping from test:
      81733 Traceback (most recent call last):
      81734 File "/home/ubuntu/autotest/client/shared/test.py", line 411, in _exec
      81735 _call_test_function(self.execute, *p_args, **p_dargs)
      81736 File "/home/ubuntu/autotest/client/shared/test.py", line 823, in _call_test_function
      81737 return func(*args, **dargs)
      81738 File "/home/ubuntu/autotest/client/shared/test.py", line 291, in execute
      81739 postprocess_profiled_run, args, dargs)
      81740 File "/home/ubuntu/autotest/client/shared/test.py", line 212, in _call_run_once
      81741 self.run_once(*args, **dargs)
      81742 File "/home/ubuntu/autotest/client/tests/ubuntu_ltp_syscalls/ubuntu_ltp_syscalls.py", line 147, in run_once
      81743 print(utils.system_output(cmd, verbose=False))
      81744 File "/home/ubuntu/autotest/client/shared/utils.py", line 1270, in system_output
      81745 verbose=verbose, args=args).stdout
      81746 File "/home/ubuntu/autotest/client/shared/utils.py", line 918, in run
      81747 "Command returned non-zero exit status")
      81748 CmdError: Command failed, rc=1, Command returned non-zero exit status
      81749 * Command:
      81750 /opt/ltp/runltp -f /tmp/target -q -C /dev/null -l /dev/null -T /dev/null
      81751 Exit status: 1
      81752 Duration: 9.19010186195
      81753
      81754 stdout:
      81755 Checking for required user/group ids
      81756
      81757 'nobody' user id and group found.
      81758 'bin' user id and group found.
      81759 'daemon' user id and group found.
      81760 Users group found.
      81761 Sys group found.
      81762 Required users/groups exist.
      81763 no big block device was specified on commandline.
      81764 Tests which require a big block device are disabled.
      81765 You can specify it with option -z
      81766 INFO: Test start time: Wed Sep 22 04:16:05 UTC 2021
      81767 COMMAND: /opt/ltp/bin/ltp-pan -q -e -S -a 860297 -n 860297 -f /tmp/ltp-FdyyG2anc0/alltests -l /dev/null -C /dev/null -T /dev/null
      81768 LOG File: /dev/null
      81769 FAILED COMMAND File: /dev/null
      81770 TCONF COMMAND File: /dev/null
      81771 Running tests.......
      81772 tst_test.c:1353: TINFO: Timeout per run is 0h 05m 00s
      81773 perf_event_open02.c:104: TINFO: bench_work estimated loops = 5731 in 500 ms
      81774 perf_event_open02.c:149: TINFO: [0] value:1720053786 time_enabled:501205704 time_running:501205704
      81775 perf_event_open02.c:149: TINFO: [1] value:1719924570 time_enabled:503196984 time_running:503196984
      81776 perf_event_open02.c:149: TINFO: [2] value:1720015802 time_enabled:508150572 time_running:508150572
      81777 perf_event_open02.c:149: TINFO: [3] value:1293358067 time_enabled:536524596 time_running:404026212
      81778 perf_event_open02.c:308: TINFO: nhw: 3, overall task clock: 4262897664
      81779 perf_event_open02.c:309: TINFO: hw sum: 41279066217, task clock sum: 12789762852
      81780 perf_event_open02.c:321: TINFO: ratio: 3.000251
      81781 perf_event_open02.c:323: TFAIL: test failed (ratio was greater than 3)
      81782
      81783 Summary:
      81784 passed 0
      81785 failed 1
      81786 broken 0
      81787 skipped 0
      81788 warnings 0
      81789 INFO: ltp-pan reported some tests FAIL
      81790 LTP Version: 20210524
      81791 INFO: Test end time: Wed Sep 22 04:16:14 UTC 2021
      81792 04:16:14 ERROR| child process failed
      81793 04:16:14 DEBUG| Traceback (most recent call last):
      81794 04:16:14 DEBUG| File "/home/ubuntu/autotest/client/parallel.py", line 25, in fork_start
      81795 04:16:14 DEBUG| l()
      81796 04:16:14 DEBUG| File "/home/ubuntu/autotest/client/job.py", line 505, in
      81797 04:16:14 DEBUG| l = lambda: test.runtest(self, url, tag, args, dargs)
      81798 04:16:14 DEBUG| File "/home/ubuntu/autotest/client/test.py", line 125, in runtest
      81799 04:16:14 DEBUG| job.sysinfo.log_after_each_iteration)
      81800 04:16:14 DEBUG| File "/home/ubuntu/autotest/client/shared/test.py", line 913, in runtest
      81801 04:16:14 DEBUG| mytest._exec(args, dargs)
      81802 04:16:14 DEBUG| File "/home/ubuntu/autotest/client/shared/test.py", line 411, in _exec
      81803 04:16:14 DEBUG| _call_test_function(self.execute, *p_args, **p_dargs)
      81804 04:16:14 DEBUG| File "/home/ubuntu/autotest/client/shared/test.py", line 823, in _call_test_function
      81805 04:16:14 DEBUG| return func(*args, **dargs)
      81806 04:16:14 DEBUG| File "/home/ubuntu/autotest/client/shared/test.py", line 291, in execute
      81807 04:16:14 DEBUG| postprocess_profiled_run, args, dargs)
      81808 04:16:14 DEBUG| File "/home/ubuntu/autotest/client/shared/test.py", line 212, in _call_run_once
      81809 04:16:14 DEBUG| self.run_once(*args, **dargs)
      81810 04:16:14 DEBUG| File "/home/ubuntu/autotest/client/tests/ubuntu_ltp_syscalls/ubuntu_ltp_syscalls.py", line 147, in run_once
      81811 04:16:14 DEBUG| print(utils.system_output(cmd, verbose=False))
      81812 04:16:14 DEBUG| File "/home/ubuntu/autotest/client/shared/utils.py", line 1270, in system_output
      81813 04:16:14 DEBUG| verbose=verbose, args=args).stdout
      81814 04:16:14 DEBUG| File "/home/ubuntu/autotest/client/shared/utils.py", line 918, in run
      81815 04:16:14 DEBUG| "Command returned non-zero exit status")
      81816 04:16:14 DEBUG| CmdError: Command failed, rc=1, Command returned non-zero exit status
      81817 04:16:14 DEBUG| * Command:
      81818 04:16:14 DEBUG| /opt/ltp/runltp -f /tmp/target -q -C /dev/null -l /dev/null -T /dev/null
      81819 04:16:14 DEBUG| Exit status: 1
      81820 04:16:14 DEBUG| Duration: 9.19010186195
      81821 04:16:14 DEBUG|
      81822 04:16:14 DEBUG| stdout:
      81823 04:16:14 DEBUG| Checking for required user/group ids
      81824 04:16:14 DEBUG|
      81825 04:16:14 DEBUG| 'nobody' user id and group found.
      81826 04:16:14 DEBUG| 'bin' user id and group found.
      81827 04:16:14 DEBUG| 'daemon' user id and group found.
      81828 04:16:14 DEBUG| Users group found.
      81829 04:16:14 DEBUG| Sys group found.
      81830 04:16:14 DEBUG| Required users/groups exist.
      81831 04:16:14 DEBUG| no big block device was specified on commandline.
      81832 04:16:14 DEBUG| Tests which require a big block device are disabled.
      81833 04:16:14 DEBUG| You can specify it with option -z
      81834 04:16:14 DEBUG| INFO: Test start time: Wed Sep 22 04:16:05 UTC 2021
      81835 04:16:14 DEBUG| COMMAND: /opt/ltp/bin/ltp-pan -q -e -S -a 860297 -n 860297 -f /tmp/ltp-FdyyG2anc0/alltests -l /dev/null -C /dev/null -T /dev/null
      81836 04:16:14 DEBUG| LOG File: /dev/null
      81837 04:16:14 DEBUG| FAILED COMMAND File: /dev/null
      81838 04:16:14 DEBUG| TCONF COMMAND File: /dev/null
      81839 04:16:14 DEBUG| Running tests.......
      81840 04:16:14 DEBUG| tst_test.c:1353: TINFO: Timeout per run is 0h 05m 00s
      81841 04:16:14 DEBUG| perf_event_open02.c:104: TINFO: bench_work estimated loops = 5731 in 500 ms
      81842 04:16:14 DEBUG| perf_event_open02.c:149: TINFO: [0] value:1720053786 time_enabled:501205704 time_running:501205704
      81843 04:16:14 DEBUG| perf_event_open02.c:149: TINFO: [1] value:1719924570 time_enabled:503196984 time_running:503196984
      81844 04:16:14 DEBUG| perf_event_open02.c:149: TINFO: [2] value:1720015802 time_enabled:508150572 time_running:508150572
      81845 04:16:14 DEBUG| perf_event_open02.c:149: TINFO: [3] value:1293358067 time_enabled:536524596 time_running:404026212
      81846 04:16:14 DEBUG| perf_event_open02.c:308: TINFO: nhw: 3, overall task clock: 4262897664
      81847 04:16:14 DEBUG| perf_event_open02.c:309: TINFO: hw sum: 41279066217, task clock sum: 12789762852
      81848 04:16:14 DEBUG| perf_event_open02.c:321: TINFO: ratio: 3.000251
      81849 04:16:14 DEBUG| perf_event_open02.c:323: TFAIL: test failed (ratio was greater than 3)
      81850 04:16:14 DEBUG|
      81851 04:16:14 DEBUG| Summary:
      81852 04:16:14 DEBUG| passed 0
      81853 04:16:14 DEBUG| failed 1
      81854 04:16:14 DEBUG| broken 0
      81855 04:16:14 DEBUG| skipped 0
      81856 04:16:14 DEBUG| warnings 0
      81857 04:16:14 DEBUG| INFO: ltp-pan reported some tests FAIL
      81858 04:16:14 DEBUG| LTP Version: 20210524
      81859 04:16:14 DEBUG| INFO: Test end time: Wed Sep 22 04:16:14 UTC 2021
      81860 04:16:15 INFO | ERROR ubuntu_ltp_syscalls.perf_event_open02 ubuntu_ltp_syscalls.perf_event_open02 timestamp=1632284175 localtime=Sep 22 04:16:15 Command failed, rc=1, Command returned non-zero exit status
      81861 * Command:
      81862 /opt/ltp/runltp -f /tmp/target -q -C /dev/null -l /dev/null -T /dev/null
      81863 Exit status: 1
      81864 Duration: 9.19010186195
      81865
      81866 stdout:
      81867 Checking for required user/group ids
      81868
      81869 'nobody' user id and group found.
      81870 'bin' user id and group found.
      81871 'daemon' user id and group found.
      81872 Users group found.
      81873 Sys group found.
      81874 Required users/groups exist.
      81875 no big block device was specified on commandline.
      81876 Tests which require a big block device are disabled.
      81877 You can specify it with option -z
      81878 INFO: Test start time: Wed Sep 22 04:16:05 UTC 2021
      81879 COMMAND: /opt/ltp/bin/ltp-pan -q -e -S -a 860297 -n 860297 -f /tmp/ltp-FdyyG2anc0/alltests -l /dev/null -C /dev/null -T /dev/null
      81880 LOG File: /dev/null
      81881 FAILED COMMAND File: /dev/null
      81882 TCONF COMMAND File: /dev/null
      81883 Running tests.......
      81884 tst_test.c:1353: TINFO: Timeout per run is 0h 05m 00s
      81885 perf_event_open02.c:104: TINFO: bench_work estimated loops = 5731 in 500 ms
      81886 perf_event_open02.c:149: TINFO: [0] value:1720053786 time_enabled:501205704 time_running:501205704
      81887 perf_event_open02.c:149: TINFO: [1] value:1719924570 time_enabled:503196984 time_running:503196984
      81888 perf_event_open02.c:149: TINFO: [2] value:1720015802 time_enabled:508150572 time_running:508150572
      81889 perf_event_open02.c:149: TINFO: [3] value:1293358067 time_enabled:536524596 time_running:404026212
      81890 perf_event_open02.c:308: TINFO: nhw: 3, overall task clock: 4262897664
      81891 perf_event_open02.c:309: TINFO: hw sum: 41279066217, task clock sum: 12789762852
      81892 perf_event_open02.c:321: TINFO: ratio: 3.000251
      81893 perf_event_open02.c:323: TFAIL: test failed (ratio was greater than 3)
      81894
      81895 Summary:
      81896 passed 0
      81897 failed 1
      81898 broken 0
      81899 skipped 0
      81900 warnings 0
      81901 INFO: ltp-pan reported some tests FAIL
      81902 LTP Version: 20210524
      81903 INFO: Test end time: Wed Sep 22 04:16:14 UTC 2021
      81904 04:16:15 INFO | END ERROR ubuntu_ltp_syscalls.perf_event_open02 ubuntu_ltp_syscalls.perf_event_open02 timestamp=1632284175 localtime=Sep 22 04:16:15