fanotify22 fanotify22
fanotify22: [  823.452239] /usr/local/bin/kirk[420]: starting test fanotify22 (fanotify22)
fanotify22[ 1900.640533] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=iopl01 RESULT=skip>
fanotify22.c:281: TINFO: Mounting /dev/loop0 to /tmp/LTP_fan2GII8y/test_mnt fsty[ 1900.663452] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=iopl02 RESULT=skip>
fanotify22.c:59: TINFO: Mounting /dev/loop0 to /tmp/LTP_fan2GII8y/test_mnt fstyp=ext4 flags=21
fanotify22.c:[ 1900.685150] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=keyctl02 RESULT=pass>

tst_tmpdir[ 1899.538391] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=getxattr04 RESULT=fail>
.c:316: TINFO: Using /tmp/LTP_getIKJAl1 as tmpdir (tmpfs filesystem)
tst_device.c:96: TINFO: Found free device 0 '/dev/loop0'
tst_test.c:1170: TINFO: Formatting /dev/loop0 with xfs opts='' e[ 1899.559722] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=getxattr05 RESULT=pass>
xtra opts=''
getxattr04 getxattr04
getxattr04: [  762.837524] /usr/local/bin/kirk[420]: starting test getxattr04 (getxattr04)

fallocate06 fallocate06
fallocate06: [  682.980590] /usr/local/bin/kirk[420]: starting test fallocate06 (fallocate06)

futex_wait05 futex_wait05
futex_wait05: [  629.927764] /usr/local/bin/kirk[420]: starting test futex_wait05 (futex_wait05)

splice07 splice07
splice07: [  531.693634] /usr/local/bin/kirk[420]: starting test splice07 (splice07)
splice07.c:64: TINFO: file -> ...
splice07.c:56: TPASS: splice() on file -> file : EINVAL (22)
splice07.c:56: TPASS: splice() on file -> O_PATH file : E[ 1892.151977] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=splice09 RESULT=skip>
splice07.c:56: TPASS: splice() on file -> directory : EBADF (9)
splice07.c:56: TPASS: splice() on file -> /dev/zero : EBADF (9)
splice07.c:56: TPASS: splice() on file -> /proc/self[ 1892.173522] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=tee01 RESULT=pass>
splice07.c:56: TPASS: splice() on file -> pipe read end : EBADF (9)
splice07.c:56: TPASS: splice() on file -> unix socket : EINVAL (22)
splice07.c:56: TPASS: splice() on f[ 1892.196563] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=tee02 RESULT=pass>
splice07.c:56: TPASS: splice() on file -> epoll : EINVAL (22)
splice07.c:56: TPASS: splice() on file -> eventfd : EINVAL (22)
splice07.c:56: TPASS: splice()[ 1892.219177] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=stat02 RESULT=pass>
splice07.c:56: TPASS: splice() on file -> timerfd : EINVAL (22)
splice07.c:56: TPASS: splice() on file -> pidfd : EINVAL (22)
splice07.c:56: TPASS: splice[ 1892.243896] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=statmount03 RESULT=skip>
splice07.c:56: TPASS: splice() on file -> inotify : EBADF (9)
splice07.c:56: TPASS: splice() on f[ 1892.266052] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=statfs02 RESULT=pass>
splice07.c:56: TPASS: splice() on file -> io uring : EINVAL (22)
splice07.c:56: TPASS: splice() on file ->[ 1892.289062] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=statvfs02 RESULT=pass>
splice07.c:56: TPASS: splice() on file -> fspick : EINVAL (22)
splice07.c:56: TPASS: splice() on file -> open_tree : EBADF (9)
splice07.c:56: TPASS: splice() on file -[ 1892.311950] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=symlink02 RESULT=pass>

semctl08 semctl08
semctl08: [  509.293579] /usr/local/bin/kirk[420]: starting test semctl08 (semctl08)
semctl08.c:38: TFAIL: time_high fields aren't cleared by the kernel

tst_test.c:1722: TINFO: Overall timeout per run is 0h 21m 36s
select_var.h:109: TINFO: Testing libc select()
tst_timer_test.c:357: TINFO: CLOCK_MONOTONIC resolution 1ns
tst_timer_test.c:369: TINFO: prctl(PR_GET_TIMERSLACK) = 50us
tst_test.c:1743: TINFO: Updating runtime to 0h 00m 09s
tst_test.c:1722: TINFO: Overall timeout per run is 0h 21m 45s
tst_timer_test.c:263: TINFO: select() sleeping for 1000us 500 iterations, threshold 450.01us
tst_timer_test.c:305: TINFO: min 1068us, max 1160us, median 1068us, trunc mean 1081.05us (discarded 25)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 2000us 500 iterations, threshold 450.01us
tst_timer_test.c:305: TINFO: min 2075us, max 2136us, median 2075us, trunc mean 2083.29us (discarded 25)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 5000us 300 iterations, threshold 450.04us
tst_timer_test.c:305: TINFO: min 5066us, max 5188us, median 5096us, trunc mean 5091.77us (discarded 15)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 10000us 100 iterations, threshold 450.33us
tst_timer_test.c:305: TINFO: min 10071us, max 10132us, median 10101us, trunc mean 10100.21us (discarded 5)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 25000us 50 iterations, threshold 451.29us
tst_timer_test.c:305: TINFO: min 25085us, max 25238us, median 25116us, trunc mean 25119.69us (discarded 2)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 100000us 10 iterations, threshold 537.00us
tst_timer_test.c:305: TINFO: min 100128us, max 100220us, median 100189us, trunc mean 100185.67us (discarded 1)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 1000000us 2 iterations, threshold 4400.00us
tst_timer_test.c:305: TINFO: min 1001038us, max 1001099us, median 1001038us, trunc mean 1001038.00us (discarded 1)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
select_var.h:112: TINFO: Testing SYS_select syscall
tst_timer_test.c:357: TINFO: CLOCK_MONOTONIC resolution 1ns
tst_timer_test.c:369: TINFO: prctl(PR_GET_TIMERSLACK) = 50us
tst_test.c:1743: TINFO: Updating runtime to 0h 00m 09s
tst_test.c:1722: TINFO: Overall timeout per run is 0h 21m 45s
tst_timer_test.c:263: TINFO: select() sleeping for 1000us 500 iterations, threshold 450.01us
select_var.h:40: TCONF: syscall(-1) __NR_select not supported on your arch
select_var.h:115: TINFO: Testing SYS_pselect6 syscall
tst_timer_test.c:357: TINFO: CLOCK_MONOTONIC resolution 1ns
tst_timer_test.c:369: TINFO: prctl(PR_GET_TIMERSLACK) = 50us
tst_test.c:1743: TINFO: Updating runtime to 0h 00m 09s
tst_test.c:1722: TINFO: Overall timeout per run is 0h 21m 45s
tst_timer_test.c:263: TINFO: select() sleeping for 1000us 500 iterations, threshold 450.01us
tst_timer_test.c:305: TINFO: min 1068us, max 1190us, median 1068us, trunc mean 1080.79us (discarded 25)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 2000us 500 iterations, threshold 450.01us
tst_timer_test.c:305: TINFO: min 2075us, max 2136us, median 2075us, trunc mean 2084.72us (discarded 25)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 5000us 300 iterations, threshold 450.04us
tst_timer_test.c:305: TINFO: min 5066us, max 5127us, median 5096us, trunc mean 5090.28us (discarded 15)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 10000us 100 iterations, threshold 450.33us
tst_timer_test.c:305: TINFO: min 10071us, max 10132us, median 10101us, trunc mean 10098.58us (discarded 5)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 25000us 50 iterations, threshold 451.29us
tst_timer_test.c:305: TINFO: min 25085us, max 25146us, median 25116us, trunc mean 25113.98us (discarded 2)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 100000us 10 iterations, threshold 537.00us
tst_timer_test.c:305: TINFO: min 100189us, max 100220us, median 100189us, trunc mean 100202.78us (discarded 1)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 1000000us 2 iterations, threshold 4400.00us
tst_timer_test.c:305: TINFO: min 1001312us, max 1001648us, median 1001312us, trunc mean 1001312.00us (discarded 1)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
select_var.h:118: TINFO: Testing SYS_pselect6 time64 syscall
tst_timer_test.c:357: TINFO: CLOCK_MONOTONIC resolution 1ns
tst_timer_test.c:369: TINFO: prctl(PR_GET_TIMERSLACK) = 50us
tst_test.c:1743: TINFO: Updating runtime to 0h 00m 09s
tst_test.c:1722: TINFO: Overall timeout per run is 0h 21m 45s
tst_timer_test.c:263: TINFO: select() sleeping for 1000us 500 iterations, threshold 450.01us
tst_timer_test.c:305: TINFO: min 1038us, max 1221us, median 1099us, trunc mean 1083.34us (discarded 25)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 2000us 500 iterations, threshold 450.01us
tst_timer_test.c:305: TINFO: min 2075us, max 2136us, median 2075us, trunc mean 2085.70us (discarded 25)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 5000us 300 iterations, threshold 450.04us
tst_timer_test.c:305: TINFO: min 5066us, max 5127us, median 5096us, trunc mean 5089.65us (discarded 15)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 10000us 100 iterations, threshold 450.33us
tst_timer_test.c:305: TINFO: min 10071us, max 10162us, median 10101us, trunc mean 10099.25us (discarded 5)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 25000us 50 iterations, threshold 451.29us
tst_timer_test.c:305: TINFO: min 25085us, max 25177us, median 25116us, trunc mean 25121.60us (discarded 2)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 100000us 10 iterations, threshold 537.00us
tst_timer_test.c:305: TINFO: min 100189us, max 100220us, median 100220us, trunc mean 100213.11us (discarded 1)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
tst_timer_test.c:263: TINFO: select() sleeping for 1000000us 2 iterations, threshold 4400.00us
tst_timer_test.c:305: TINFO: min 1001495us, max 1001648us, median 1001495us, trunc mean 1001495.00us (discarded 1)
tst_timer_test.c:326: TPASS: Measured times are within thresholds
select_var.h:121: TINFO: Testing SYS__newselect syscall
tst_timer_test.c:357: TINFO: CLOCK_MONOTONIC resolution 1ns
tst_timer_test.c:369: TINFO: prctl(PR_GET_TIMERSLACK) = 50us
tst_test.c:1743: TINFO: Updating runtime to 0h 00m 09s
tst_test.c:1722: TINFO: Overall timeout per run is 0h 21m 45s
tst_timer_test.c:263: TINFO: select() sleeping for 1000us 500 iterations, threshold 450.01us
tst_timer_test.c:292: TFAIL: [ 1890.360229] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=select02 RESULT=fail>
select02 select02
select02: [  481.306121] /usr/local/bin/kirk[420]: starting test select02 (select02)

recvmmsg01 recvmmsg01
recvmmsg01: [  466.584991] /usr/local/bin/kirk[420]: starting test recvmmsg01 (recvmmsg01)
recvmmsg01.c:133: TINFO: Testing variant: vDSO or syscall with libc spec
recvmmsg01.c:86: TPASS: recvmmsg() bad socket file descriptor : EBADF (9)

tst_kconfig.c:88: TINFO: Parsing kernel config '/proc/config.gz'
readahead01 readahead01
readahead01: [  465.993560] /usr/local/bin/kirk[420]: starting test readahead01 (readahead01)
readahead01.c:36: TPASS: readahead() with fd = -1 : EBADF (9)
readahead01[ 1888.832061] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=readlinkat02 RESULT=pass>
readahead01.c:64: TPASS: readahead() on O_PATH file : EBADF (9)
readahead01.c:64: TPASS: readahead() on directory : EINVAL (22)
readahead01.c:64: TPASS: readahead() on pipe read end : EINVAL (22)
readahead01.c:64: TPASS: readahead() on pipe write end : EBADF (9[ 1888.877441] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=readv02 RESULT=pass>
readahead01.c:64: TPASS: readahead() on unix socket : EINVAL (22)
readahead01.c:64: TPASS: readahead() on inet socket : EINVAL (22)
readahead01.c:64: TPASS: readahead() on epoll : EINVAL [ 1888.898468] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=recvmsg01 RESULT=pass>
readahead01.c:64: TPASS: readahead() on eventfd : EINVAL (22)
readahead01.c:64: TPASS: readahead() on signalfd : EINVAL (22)
readahead01.c:64: TPASS: readahead() on timerfd : EINVAL (2[ 1888.921936] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=recvmsg02 RESULT=pass>
readahead01.c:64: TPASS: readahead() on fanotify : EINVAL (22)
readahead01.c:64: TPASS: readahead() on inotify : EINVAL (22)
readahead01.c:64: TPASS: readahead() on io uring : EINVAL (22)
readahead01.c:6[ 1888.970001] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=recvmmsg01 RESULT=fail>
readahead01.c:64: TPASS: readahead() on fspick : EINVAL (22)
readahead01.c:64: TPASS: readahead() on open_tree : EBADF (9)

t[ 1886.674530] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=msgctl12 RESULT=pass>
msgctl05 msgctl05
msgctl05: [  243.767181] /usr/local/bin/kirk[420]: starting test msgctl05 (msgctl05)
msgctl05.c:35: TFAIL: time_high fields aren't cleared by the kernel

TINFO: Starting listener on port: 59841
accept03 accept03
accept03: [   44.976074] /usr/local/bin/kirk[420]: starting test accept03 (accept03)
accept03.c:48: TPASS: accept() on file : ENOTSOCK (88)
accept03.c:48: TPASS: accept() on O_PATH file : EBADF (9)
accept03.c:48: TPASS: accept() on directory : ENOTS[ 1879.441955] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=alarm03 RESULT=pass>
accept03.c:48: TPASS: accept() on /dev/zero : ENOTSOCK (88)
accept03.c:48: TPASS: accept() on /proc/self/maps : ENOTSOCK (88)
accept03.c:48: TPASS: accept() on pipe read end : ENOT[ 1879.468139] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=alarm05 RESULT=pass>
accept03.c:48: TPASS: accept() on pipe write end : ENOTSOCK (88)
accept03.c:48: TPASS: accept() on epoll : ENOTSOCK (88)
accept03.c:48: TPASS: accept() on eventfd : ENOTSOCK (88)
[ 1879.489929] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=alarm06 RESULT=pass>
accept03.c:48: TPASS: accept() on signalfd : ENOTSOCK (88)
accept03.c:48: TPASS: accept() on timerfd : ENOTSOCK (88)
accept03.c:48: TPASS: accept() on pidfd : ENOTSOCK (88)
accept03.c:48: [ 1879.512817] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=alarm07 RESULT=pass>
accept03.c:48: TPASS: accept() on inotify : ENOTSOCK (88)
accept03.c:48: TPASS: accept() o[ 1879.537963] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=arch_prctl01 RESULT=skip>
accept03.c:48: TPASS: accept() on io uring : ENOTSOCK (88)
accept03.c:48: TPASS: accept() on fsopen : ENOTSOC[ 1879.559326] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=bind01 RESULT=pass>
accept03.c:48: TPASS: accept() on fspick : ENOTSOCK (88)
accept03.c:48: TPASS: accept() on open_tree : EBADF (9)
accept03.c:48: TPASS: accept() on memfd : ENOTSOCK (88)

accept02 accept02
accept02: [   44.915954] /usr/local/bin/kirk[420]: starting test accept02 (accept02)
accept02.c:131: [ 1879.321624] <LAVA_SIGNAL_TESTCASE TEST_CASE_ID=accept4_01 RESULT=pass>
accept02.c:54: TBROK: setsockopt(3, 0, 42, 0xb6fcef7c, 132) failed: ENODEV (19)