LTP syscalls statx06 fails on NFS mounted devices using external hard drives
for testing and running on Linux next 6.4.0-rc3-next-20230524.
Test case fails on x86_64, i386 and arm64 Juno-r2.
Reported-by: Linux Kernel Functional Testing <[email protected]>
Linux version 6.4.0-rc3-next-20230524 (tuxmake@tuxmake)
(x86_64-linux-gnu-gcc (Debian 11.3.0-12) 11.3.0, GNU ld (GNU Binutils
for Debian) 2.40) #1 SMP PREEMPT_DYNAMIC @1684908723
...
[ 1.396191] Mountpoint-cache hash table entries: 32768 (order: 6,
262144 bytes, linear)
[ 1.397234] sysctl table check failed: kernel/usermodehelper Not a file
[ 1.398166] sysctl table check failed: kernel/usermodehelper No proc_handler
[ 1.399165] sysctl table check failed: kernel/usermodehelper bogus .mode 0555
[ 1.400166] sysctl table check failed: kernel/keys Not a file
[ 1.401165] sysctl table check failed: kernel/keys No proc_handler
[ 1.402165] sysctl table check failed: kernel/keys bogus .mode 0555
[ 1.403166] CPU: 0 PID: 0 Comm: swapper/0 Not tainted
6.4.0-rc3-next-20230524 #1
[ 1.404165] Hardware name: Supermicro SYS-5019S-ML/X11SSH-F, BIOS
2.5 11/26/2020
[ 1.404165] Call Trace:
[ 1.404165] <TASK>
[ 1.404165] dump_stack_lvl+0x72/0x90
[ 1.404165] dump_stack+0x14/0x20
[ 1.404165] __register_sysctl_table+0x570/0x840
[ 1.404165] __register_sysctl_init+0x29/0x60
[ 1.404165] sysctl_init_bases+0x27/0x80
[ 1.404165] proc_sys_init+0x37/0x40
[ 1.404165] proc_root_init+0x7b/0x90
[ 1.404165] start_kernel+0x403/0x6a0
[ 1.404165] x86_64_start_reservations+0x1c/0x30
[ 1.404165] x86_64_start_kernel+0xcb/0xe0
[ 1.404165] secondary_startup_64_no_verify+0x179/0x17b
[ 1.404165] </TASK>
[ 1.404165] failed when register_sysctl kern_table to kernel
....
./runltp -f syscalls -d /scratch
...
[ 1192.088987] loop0: detected capacity change from 0 to 614400
tst_device.c:93: TINFO: Found free device 0 '/dev/loop0'
tst_test.c:1093: TINFO: Formatting /dev/loop0 with ext4 opts='-I 256'
extra opts=''
mke2fs 1.46.5 (30-Dec-2021)
[ 1192.337350] EXT4-fs (loop0): mounted filesystem
dfe9283c-5d2f-43f8-840e-a2bbbff5b202 r/w with ordered data mode. Quota
mode: none.
tst_test.c:1558: TINFO: Timeout per run is 0h 05m 00s
statx06.c:140: TPASS: Birth time Passed
statx06.c:138: TFAIL: Modified time > after_time
statx06.c:140: TPASS: Access time Passed
statx06.c:140: TPASS: Change time Passed
links,
- https://qa-reports.linaro.org/lkft/linux-next-master/build/next-20230524/testrun/17171892/suite/ltp-syscalls/test/statx06/log
- https://qa-reports.linaro.org/lkft/linux-next-master/build/next-20230524/testrun/17171892/suite/ltp-syscalls/test/statx06/history/
- https://qa-reports.linaro.org/lkft/linux-next-master/build/next-20230524/testrun/17171892/suite/ltp-syscalls/test/statx06/details/
--
Linaro LKFT
https://lkft.linaro.org
On Wed, May 24, 2023, at 12:48, Naresh Kamboju wrote:
>
> ...
>
> [ 1192.088987] loop0: detected capacity change from 0 to 614400
> tst_device.c:93: TINFO: Found free device 0 '/dev/loop0'
> tst_test.c:1093: TINFO: Formatting /dev/loop0 with ext4 opts='-I 256'
> extra opts=''
> mke2fs 1.46.5 (30-Dec-2021)
> [ 1192.337350] EXT4-fs (loop0): mounted filesystem
> dfe9283c-5d2f-43f8-840e-a2bbbff5b202 r/w with ordered data mode. Quota
> mode: none.
> tst_test.c:1558: TINFO: Timeout per run is 0h 05m 00s
>
> statx06.c:140: TPASS: Birth time Passed
> statx06.c:138: TFAIL: Modified time > after_time
> statx06.c:140: TPASS: Access time Passed
> statx06.c:140: TPASS: Change time Passed
I found a description in
https://lwn.net/ml/linux-kernel/[email protected]/
which indicates that this is expected. Added Jeff to Cc in case
I'm misreading his explanation.
Arnd
I am pretty sure this is caused by commit 7eec88986dce ("sysctl:
Refactor base paths registrations"). Add Joel to the CC list.
Before we used to use register_sysctl_table() to register that table.
regards,
dan carpenter
On Wed, May 24, 2023 at 04:18:42PM +0530, Naresh Kamboju wrote:
> LTP syscalls statx06 fails on NFS mounted devices using external hard drives
> for testing and running on Linux next 6.4.0-rc3-next-20230524.
>
> Test case fails on x86_64, i386 and arm64 Juno-r2.
>
> Reported-by: Linux Kernel Functional Testing <[email protected]>
>
>
> Linux version 6.4.0-rc3-next-20230524 (tuxmake@tuxmake)
> (x86_64-linux-gnu-gcc (Debian 11.3.0-12) 11.3.0, GNU ld (GNU Binutils
> for Debian) 2.40) #1 SMP PREEMPT_DYNAMIC @1684908723
> ...
>
> [ 1.396191] Mountpoint-cache hash table entries: 32768 (order: 6,
> 262144 bytes, linear)
> [ 1.397234] sysctl table check failed: kernel/usermodehelper Not a file
> [ 1.398166] sysctl table check failed: kernel/usermodehelper No proc_handler
> [ 1.399165] sysctl table check failed: kernel/usermodehelper bogus .mode 0555
> [ 1.400166] sysctl table check failed: kernel/keys Not a file
> [ 1.401165] sysctl table check failed: kernel/keys No proc_handler
> [ 1.402165] sysctl table check failed: kernel/keys bogus .mode 0555
> [ 1.403166] CPU: 0 PID: 0 Comm: swapper/0 Not tainted
> 6.4.0-rc3-next-20230524 #1
> [ 1.404165] Hardware name: Supermicro SYS-5019S-ML/X11SSH-F, BIOS
> 2.5 11/26/2020
> [ 1.404165] Call Trace:
> [ 1.404165] <TASK>
> [ 1.404165] dump_stack_lvl+0x72/0x90
> [ 1.404165] dump_stack+0x14/0x20
> [ 1.404165] __register_sysctl_table+0x570/0x840
> [ 1.404165] __register_sysctl_init+0x29/0x60
> [ 1.404165] sysctl_init_bases+0x27/0x80
> [ 1.404165] proc_sys_init+0x37/0x40
> [ 1.404165] proc_root_init+0x7b/0x90
> [ 1.404165] start_kernel+0x403/0x6a0
> [ 1.404165] x86_64_start_reservations+0x1c/0x30
> [ 1.404165] x86_64_start_kernel+0xcb/0xe0
> [ 1.404165] secondary_startup_64_no_verify+0x179/0x17b
> [ 1.404165] </TASK>
> [ 1.404165] failed when register_sysctl kern_table to kernel
>
> ....
> ./runltp -f syscalls -d /scratch
>
> ...
>
> [ 1192.088987] loop0: detected capacity change from 0 to 614400
> tst_device.c:93: TINFO: Found free device 0 '/dev/loop0'
> tst_test.c:1093: TINFO: Formatting /dev/loop0 with ext4 opts='-I 256'
> extra opts=''
> mke2fs 1.46.5 (30-Dec-2021)
> [ 1192.337350] EXT4-fs (loop0): mounted filesystem
> dfe9283c-5d2f-43f8-840e-a2bbbff5b202 r/w with ordered data mode. Quota
> mode: none.
> tst_test.c:1558: TINFO: Timeout per run is 0h 05m 00s
>
> statx06.c:140: TPASS: Birth time Passed
> statx06.c:138: TFAIL: Modified time > after_time
> statx06.c:140: TPASS: Access time Passed
> statx06.c:140: TPASS: Change time Passed
>
>
> links,
> - https://qa-reports.linaro.org/lkft/linux-next-master/build/next-20230524/testrun/17171892/suite/ltp-syscalls/test/statx06/log
> - https://qa-reports.linaro.org/lkft/linux-next-master/build/next-20230524/testrun/17171892/suite/ltp-syscalls/test/statx06/history/
> - https://qa-reports.linaro.org/lkft/linux-next-master/build/next-20230524/testrun/17171892/suite/ltp-syscalls/test/statx06/details/
>
>
> --
> Linaro LKFT
> https://lkft.linaro.org
Hi!
> > [ 1192.088987] loop0: detected capacity change from 0 to 614400
> > tst_device.c:93: TINFO: Found free device 0 '/dev/loop0'
> > tst_test.c:1093: TINFO: Formatting /dev/loop0 with ext4 opts='-I 256'
> > extra opts=''
> > mke2fs 1.46.5 (30-Dec-2021)
> > [ 1192.337350] EXT4-fs (loop0): mounted filesystem
> > dfe9283c-5d2f-43f8-840e-a2bbbff5b202 r/w with ordered data mode. Quota
> > mode: none.
> > tst_test.c:1558: TINFO: Timeout per run is 0h 05m 00s
> >
> > statx06.c:140: TPASS: Birth time Passed
> > statx06.c:138: TFAIL: Modified time > after_time
> > statx06.c:140: TPASS: Access time Passed
> > statx06.c:140: TPASS: Change time Passed
>
> I found a description in
>
> https://lwn.net/ml/linux-kernel/[email protected]/
>
> which indicates that this is expected. Added Jeff to Cc in case
> I'm misreading his explanation.
We even have in-flight patch from Jeff to fix the test with fine-grained
timestamps in LTP:
http://patchwork.ozlabs.org/project/ltp/patch/[email protected]/
--
Cyril Hrubis
[email protected]
On Wed, May 24, 2023 at 02:23:16PM +0300, Dan Carpenter wrote:
> I am pretty sure this is caused by commit 7eec88986dce ("sysctl:
> Refactor base paths registrations"). Add Joel to the CC list.
>
> Before we used to use register_sysctl_table() to register that table.
>
> regards,
> dan carpenter
>
> On Wed, May 24, 2023 at 04:18:42PM +0530, Naresh Kamboju wrote:
> > LTP syscalls statx06 fails on NFS mounted devices using external hard drives
> > for testing and running on Linux next 6.4.0-rc3-next-20230524.
> >
> > Test case fails on x86_64, i386 and arm64 Juno-r2.
> >
> > Reported-by: Linux Kernel Functional Testing <[email protected]>
> >
> >
> > Linux version 6.4.0-rc3-next-20230524 (tuxmake@tuxmake)
> > (x86_64-linux-gnu-gcc (Debian 11.3.0-12) 11.3.0, GNU ld (GNU Binutils
> > for Debian) 2.40) #1 SMP PREEMPT_DYNAMIC @1684908723
> > ...
> >
> > [ 1.396191] Mountpoint-cache hash table entries: 32768 (order: 6,
> > 262144 bytes, linear)
> > [ 1.397234] sysctl table check failed: kernel/usermodehelper Not a file
> > [ 1.398166] sysctl table check failed: kernel/usermodehelper No proc_handler
> > [ 1.399165] sysctl table check failed: kernel/usermodehelper bogus .mode 0555
> > [ 1.400166] sysctl table check failed: kernel/keys Not a file
> > [ 1.401165] sysctl table check failed: kernel/keys No proc_handler
> > [ 1.402165] sysctl table check failed: kernel/keys bogus .mode 0555
This should all be fixed with
https://lore.kernel.org/all/[email protected]/
> > [ 1.403166] CPU: 0 PID: 0 Comm: swapper/0 Not tainted
> > 6.4.0-rc3-next-20230524 #1
> > [ 1.404165] Hardware name: Supermicro SYS-5019S-ML/X11SSH-F, BIOS
> > 2.5 11/26/2020
> > [ 1.404165] Call Trace:
> > [ 1.404165] <TASK>
> > [ 1.404165] dump_stack_lvl+0x72/0x90
> > [ 1.404165] dump_stack+0x14/0x20
> > [ 1.404165] __register_sysctl_table+0x570/0x840
> > [ 1.404165] __register_sysctl_init+0x29/0x60
> > [ 1.404165] sysctl_init_bases+0x27/0x80
> > [ 1.404165] proc_sys_init+0x37/0x40
> > [ 1.404165] proc_root_init+0x7b/0x90
> > [ 1.404165] start_kernel+0x403/0x6a0
> > [ 1.404165] x86_64_start_reservations+0x1c/0x30
> > [ 1.404165] x86_64_start_kernel+0xcb/0xe0
> > [ 1.404165] secondary_startup_64_no_verify+0x179/0x17b
> > [ 1.404165] </TASK>
> > [ 1.404165] failed when register_sysctl kern_table to kernel
> >
> > ....
> > ./runltp -f syscalls -d /scratch
> >
> > ...
> >
> > [ 1192.088987] loop0: detected capacity change from 0 to 614400
> > tst_device.c:93: TINFO: Found free device 0 '/dev/loop0'
> > tst_test.c:1093: TINFO: Formatting /dev/loop0 with ext4 opts='-I 256'
> > extra opts=''
> > mke2fs 1.46.5 (30-Dec-2021)
> > [ 1192.337350] EXT4-fs (loop0): mounted filesystem
> > dfe9283c-5d2f-43f8-840e-a2bbbff5b202 r/w with ordered data mode. Quota
> > mode: none.
> > tst_test.c:1558: TINFO: Timeout per run is 0h 05m 00s
> >
> > statx06.c:140: TPASS: Birth time Passed
> > statx06.c:138: TFAIL: Modified time > after_time
> > statx06.c:140: TPASS: Access time Passed
> > statx06.c:140: TPASS: Change time Passed
> >
> >
> > links,
> > - https://protect2.fireeye.com/v1/url?k=cdaccb27-acd761af-cdad4068-74fe4860018a-69c3d7b272f81ebe&q=1&e=6d71a9ab-f4cd-4235-b09a-35fa9af4bdc2&u=https%3A%2F%2Fqa-reports.linaro.org%2Flkft%2Flinux-next-master%2Fbuild%2Fnext-20230524%2Ftestrun%2F17171892%2Fsuite%2Fltp-syscalls%2Ftest%2Fstatx06%2Flog
> > - https://protect2.fireeye.com/v1/url?k=04c58bbf-65be2137-04c400f0-74fe4860018a-ee117eefec304706&q=1&e=6d71a9ab-f4cd-4235-b09a-35fa9af4bdc2&u=https%3A%2F%2Fqa-reports.linaro.org%2Flkft%2Flinux-next-master%2Fbuild%2Fnext-20230524%2Ftestrun%2F17171892%2Fsuite%2Fltp-syscalls%2Ftest%2Fstatx06%2Fhistory%2F
> > - https://protect2.fireeye.com/v1/url?k=b2e23b58-d39991d0-b2e3b017-74fe4860018a-6ee7cd6348bd7aa8&q=1&e=6d71a9ab-f4cd-4235-b09a-35fa9af4bdc2&u=https%3A%2F%2Fqa-reports.linaro.org%2Flkft%2Flinux-next-master%2Fbuild%2Fnext-20230524%2Ftestrun%2F17171892%2Fsuite%2Fltp-syscalls%2Ftest%2Fstatx06%2Fdetails%2F
> >
> >
> > --
> > Linaro LKFT
> > https://protect2.fireeye.com/v1/url?k=f45a98bf-95213237-f45b13f0-74fe4860018a-bc1dde58624c2012&q=1&e=6d71a9ab-f4cd-4235-b09a-35fa9af4bdc2&u=https%3A%2F%2Flkft.linaro.org%2F
--
Joel Granados