Requesting for help in LKP kerneltests results analysis
by srilakshmi manubothu
Hi ,
I'm attaching kernel self tests results,can anyone help me in analyzing the
results?
I'm observing failures in the output file.
Can anyone send me the documents related to each test in LKP?
CONFIG_TEST_FIRMWARE IS NOT SET
CONFIG_TEST_USER_COPY IS NOT SET
CONFIG_MEMORY_NOTIFIER_ERROR_INJECT IS NOT PRESENT
CONFIG_NOTIFIER_ERROR_INJECTION IS NOT SET
CONFIG_TEST_BITMAP IS NOT SET
CONFIG_TEST_PRINTF IS NOT SET
CONFIG_TEST_STATIC_KEYS IS NOT SET
CONFIG_TEST_BPF=m IS NOT SET
CONFIG_HIST_TRIGGERS=y IS NOT SET
CONFIG_EMBEDDED=y IS NOT SET
CONFIG_GPIO_MOCKUP=y IS NOT SET
CONFIG_USERFAULTFD=y IS NOT PRESENT
CONFIG_SW_SYNC=y IS NOT SET
CONFIG_PSTORE_PMSG=y IS NOT SET
CONFIG_PSTORE_CONSOLE=y IS NOT SET
CONFIG_TEST_KMOD=m IS NOT SET
CONFIG_TEST_LKM=m IS NOT SET
CONFIG_TEST_SYSCTL=m IS NOT SET
CONFIG_NET_FOU_IP_TUNNELS=y IS NOT SET
CONFIG_X86_INTEL_MPX=y IS NOT SET
CONFIG_IPV6_SEG6_LWTUNNEL=y ~ v(4\.1[0-9]|4\.20|5\.) IS NOT SET
CONFIG_WW_MUTEX_SELFTEST=m ~ v(4\.1[1-9]|4\.20|5\.) IS NOT SET
CONFIG_DRM_DEBUG_SELFTEST=m ~ v(4\.1[8-9]|4\.20|5\.) IS NOT SET
CONFIG_TEST_LIVEPATCH=m ~ v(5\.[1-9]) IS NOT PRESENT
CONFIG_ANDROID=y ~ v(3\.[3-9]|3\.1[0-9]|4\.|5\.) IS NOT SET
CONFIG_ION=y ~ v(3\.1[4-9]|4\.|5\.) IS NOT PRESENT
CONFIG_ION_SYSTEM_HEAP=y ~ v(4\.1[2-9]|4\.20|5\.) IS NOT PRESENT
The above kconfig parameters are missing in my kernel configuaration.
Will the above kconfig parameters impact my kernel selftests?
Pls hekp me on this...
Regards,
Sri.
10 months, 1 week
[scsi] ae86a1c553: BUG:kernel_NULL_pointer_dereference, address
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: ae86a1c5530b52dc44a280e78feb0c4eb2dd8595 ("[PATCH V2 2/2] scsi: implement .cleanup_rq callback")
url: https://github.com/0day-ci/linux/commits/Ming-Lei/blk-mq-add-callback-of-...
in testcase: blktests
with following parameters:
disk: 1SSD
test: block-group1
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+---------------------------------------------------------------------------------------------------------------+------------+------------+
| | bd222ca85f | ae86a1c553 |
+---------------------------------------------------------------------------------------------------------------+------------+------------+
| boot_successes | 0 | 0 |
| boot_failures | 11 | 14 |
| BUG:kernel_reboot-without-warning_in_test_stage | 11 | 1 |
| BUG:kernel_NULL_pointer_dereference,address | 0 | 4 |
| Oops:#[##] | 0 | 4 |
| RIP:scsi_queue_rq | 0 | 4 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 4 |
| invoked_oom-killer:gfp_mask=0x | 0 | 9 |
| Mem-Info | 0 | 9 |
| page_allocation_failure:order:#,mode:#(GFP_KERNEL|__GFP_RETRY_MAYFAIL),nodemask=(null),cpuset=/,mems_allowed= | 0 | 2 |
+---------------------------------------------------------------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <rong.a.chen(a)intel.com>
[ 140.974865] BUG: kernel NULL pointer dereference, address: 000000000000001c
[ 141.013422] #PF: supervisor read access in kernel mode
[ 141.034814] #PF: error_code(0x0000) - not-present page
[ 141.042285] sd 6:0:0:0: [sdo] Write cache: enabled, read cache: enabled, supports DPO and FUA
[ 141.049589] PGD 0 P4D 0
[ 141.049616] Oops: 0000 [#1] SMP PTI
[ 141.049621] CPU: 1 PID: 384 Comm: kworker/1:1H Not tainted 5.2.0-gae86a1c5530b52 #1
[ 141.049623] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 141.049654] Workqueue: kblockd blk_mq_run_work_fn
[ 141.049684] RIP: 0010:scsi_queue_rq+0x267/0xa80
[ 141.049688] Code: b0 07 00 00 8d 4a fa 83 f9 01 0f 86 80 05 00 00 83 fa 04 0f 84 77 05 00 00 c7 83 2c 01 00 00 00 00 07 00 48 8b 93 30 02 00 00 <f6> 42 1c 80 0f 84 1d ff ff ff 48 8b 3c 24 88 44 24 08 e8 22 f5 ff
[ 141.049690] RSP: 0000:ffffab02c078fd00 EFLAGS: 00010297
[ 141.049713] RAX: 000000000000000a RBX: ffff92fe394b1200 RCX: 00000000fffffffd
[ 141.145130] sd 6:0:0:0: [sdo] Optimal transfer size 524288 bytes
[ 141.161078] RDX: 0000000000000000 RSI: ffffab02c078fd90 RDI: ffff92fe3971f400
[ 141.161080] RBP: ffff92fe39721000 R08: 00000000000003c0 R09: 8080808080808080
[ 141.161082] R10: ffffab02c0373858 R11: fefefefefefefeff R12: ffff92fe3971f400
[ 141.161084] R13: 0000000000000000 R14: ffff92fe3ef93918 R15: ffff92fe3946a800
[ 141.161108] FS: 0000000000000000(0000) GS:ffff92feffd00000(0000) knlGS:0000000000000000
[ 141.161110] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 141.161111] CR2: 000000000000001c CR3: 000000007f2ec000 CR4: 00000000000006e0
[ 141.161143] Call Trace:
[ 141.548583] blk_mq_dispatch_rq_list+0x3c3/0x5c0
[ 141.563298] ? syscall_return_via_sysret+0x10/0x7f
[ 141.577641] ? __switch_to_asm+0x40/0x70
[ 141.603426] ? elv_rb_del+0x1f/0x30
[ 141.615379] ? deadline_remove_request+0x55/0xc0
[ 141.631520] blk_mq_do_dispatch_sched+0x76/0x120
[ 141.647829] blk_mq_sched_dispatch_requests+0x100/0x170
[ 141.667954] __blk_mq_run_hw_queue+0x60/0x130
[ 141.697797] process_one_work+0x19c/0x3b0
[ 141.714051] worker_thread+0x3c/0x3b0
[ 141.735158] ? process_one_work+0x3b0/0x3b0
[ 141.748670] kthread+0x11e/0x140
[ 141.761030] ? kthread_park+0xa0/0xa0
[ 141.784865] ret_from_fork+0x35/0x40
[ 141.804669] Modules linked in: scsi_debug loop sr_mod cdrom sd_mod sg intel_rapl_msr ppdev bochs_drm drm_vram_helper ata_generic ttm pata_acpi drm_kms_helper syscopyarea sysfillrect sysimgblt snd_pcm fb_sys_fops snd_timer snd intel_rapl_common crc32c_intel soundcore joydev ata_piix pcspkr drm serio_raw virtio_scsi libata i2c_piix4 floppy parport_pc parport ip_tables
[ 141.928554] CR2: 000000000000001c
[ 141.944180] ---[ end trace 9f0c0bf804097727 ]---
To reproduce:
# build kernel
cd linux
cp config-5.2.0-gae86a1c5530b52 .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Rong Chen
10 months, 1 week
[selftests/tls] cf32526c88: kernel_selftests.net.tls.fail
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: cf32526c8842781e32d6652a1e01e83b20490948 ("selftests/tls: add a test for ULP but no keys")
https://kernel.googlesource.com/pub/scm/linux/kernel/git/next/linux-next.git pending-fixes
in testcase: kernel_selftests
with following parameters:
group: kselftests-02
test-description: The kernel contains a set of "self tests" under the tools/testing/selftests/ directory. These are intended to be small unit tests to exercise individual code paths in the kernel.
test-url: https://www.kernel.org/doc/Documentation/kselftest.txt
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 8G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <rong.a.chen(a)intel.com>
# selftests: net: tls
# tls.c:72:tls_basic.base_base:Expected errno (2) == ENOTSUPP (524)
# tls_basic.base_base: Test terminated by assertion
# [==========] Running 35 tests from 3 test cases.
# [ RUN ] tls_basic.base_base
# [ FAIL ] tls_basic.base_base
# [ RUN ] tls.sendfile
# [ OK ] tls.sendfile
# [ RUN ] tls.send_then_sendfile
# [ OK ] tls.send_then_sendfile
# [ RUN ] tls.recv_max
# [ OK ] tls.recv_max
# [ RUN ] tls.recv_small
# [ OK ] tls.recv_small
# [ RUN ] tls.msg_more
# [ OK ] tls.msg_more
# [ RUN ] tls.sendmsg_single
# [ OK ] tls.sendmsg_single
# [ RUN ] tls.sendmsg_large
# [ OK ] tls.sendmsg_large
# [ RUN ] tls.sendmsg_multiple
# [ OK ] tls.sendmsg_multiple
# [ RUN ] tls.sendmsg_multiple_stress
# [ OK ] tls.sendmsg_multiple_stress
# [ RUN ] tls.splice_from_pipe
# [ OK ] tls.splice_from_pipe
# [ RUN ] tls.splice_from_pipe2
# [ OK ] tls.splice_from_pipe2
# [ RUN ] tls.send_and_splice
# [ OK ] tls.send_and_splice
# [ RUN ] tls.splice_to_pipe
# [ OK ] tls.splice_to_pipe
# [ RUN ] tls.recvmsg_single
# [ OK ] tls.recvmsg_single
# [ RUN ] tls.recvmsg_single_max
# [ OK ] tls.recvmsg_single_max
# [ RUN ] tls.recvmsg_multiple
# [ OK ] tls.recvmsg_multiple
# [ RUN ] tls.single_send_multiple_recv
# [ OK ] tls.single_send_multiple_recv
# [ RUN ] tls.multiple_send_single_recv
# [ OK ] tls.multiple_send_single_recv
# [ RUN ] tls.single_send_multiple_recv_non_align
# [ OK ] tls.single_send_multiple_recv_non_align
# [ RUN ] tls.recv_partial
# [ OK ] tls.recv_partial
# [ RUN ] tls.recv_nonblock
# [ OK ] tls.recv_nonblock
# [ RUN ] tls.recv_peek
# [ OK ] tls.recv_peek
# [ RUN ] tls.recv_peek_multiple
# [ OK ] tls.recv_peek_multiple
# [ RUN ] tls.recv_peek_multiple_records
# [ OK ] tls.recv_peek_multiple_records
# [ RUN ] tls.recv_peek_large_buf_mult_recs
# [ OK ] tls.recv_peek_large_buf_mult_recs
# [ RUN ] tls.recv_lowat
# [ OK ] tls.recv_lowat
# [ RUN ] tls.pollin
# [ OK ] tls.pollin
# [ RUN ] tls.poll_wait
# [ OK ] tls.poll_wait
# [ RUN ] tls.poll_wait_split
# [ OK ] tls.poll_wait_split
# [ RUN ] tls.blocking
# [ OK ] tls.blocking
# [ RUN ] tls.nonblocking
# [ OK ] tls.nonblocking
# [ RUN ] tls.control_msg
# [ OK ] tls.control_msg
# [ RUN ] global.keysizes
# [ OK ] global.keysizes
# [ RUN ] global.tls12
# [ OK ] global.tls12
# [==========] 34 / 35 tests passed.
# [ FAILED ]
not ok 6 selftests: net: tls
To reproduce:
# build kernel
cd linux
cp config-5.2.0-08346-gcf32526c884278 .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> job-script # job-script is attached in this email
Thanks,
Rong Chen
10 months, 1 week
[NTB] 26b3a37b92: WARNING:at_kernel/params.c:#param_sysfs_init
by kernel test robot
FYI, we noticed the following commit (built with gcc-4.9):
commit: 26b3a37b928457ba2cd98eaf6d7b0feca5a30fa6 ("NTB: Introduce MSI library")
https://kernel.googlesource.com/pub/scm/linux/kernel/git/torvalds/linux.git master
in testcase: locktorture
with following parameters:
runtime: 300s
test: cpuhotplug
test-description: This torture test consists of creating a number of kernel threads which acquire the lock and hold it for specific amount of time, thus simulating different critical region behaviors.
test-url: https://www.kernel.org/doc/Documentation/locking/locktorture.txt
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 2G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+----------------------------------------------+------------+------------+
| | d217e07b32 | 26b3a37b92 |
+----------------------------------------------+------------+------------+
| boot_successes | 4 | 0 |
| boot_failures | 0 | 4 |
| WARNING:at_kernel/params.c:#param_sysfs_init | 0 | 4 |
| RIP:param_sysfs_init | 0 | 4 |
+----------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 1.062876] WARNING: CPU: 0 PID: 1 at kernel/params.c:861 param_sysfs_init+0xb1/0x20a
[ 1.064618] Modules linked in:
[ 1.065241] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 5.2.0-rc1-00018-g26b3a37b928457 #2
[ 1.066691] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 1.066851] RIP: 0010:param_sysfs_init+0xb1/0x20a
[ 1.066851] Code: 24 38 e8 ec 17 2e fd 49 8b 7c 24 38 e8 76 fe ff ff 48 85 c0 48 89 c5 74 25 31 d2 4c 89 e6 48 89 c7 e8 6d 6f 3c fd 85 c0 74 02 <0f> 0b 48 89 ef 31 f6 e8 5d 70 a7 fe 48 89 ef e8 95 52 a7 fe 48 83
[ 1.066851] RSP: 0000:ffff88806b0ffe30 EFLAGS: 00010282
[ 1.066851] RAX: 00000000ffffffef RBX: ffffffff83774220 RCX: ffff88806a85e880
[ 1.066851] RDX: 00000000ffffffef RSI: ffff88806b000400 RDI: ffff88806a8608c0
[ 1.066851] RBP: ffff88806b392000 R08: ffffed100d61ff59 R09: ffffed100d61ff59
[ 1.066851] R10: 0000000000000001 R11: ffffed100d61ff58 R12: ffffffff83974bc0
[ 1.066851] R13: 0000000000000004 R14: 0000000000000028 R15: 00000000000003b9
[ 1.066851] FS: 0000000000000000(0000) GS:ffff88806b800000(0000) knlGS:0000000000000000
[ 1.066851] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1.066851] CR2: 0000000000000000 CR3: 000000000380e000 CR4: 00000000000406b0
[ 1.066851] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 1.066851] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 1.066851] Call Trace:
[ 1.066851] ? file_caps_disable+0x10/0x10
[ 1.066851] ? locate_module_kobject+0xf2/0xf2
[ 1.066851] do_one_initcall+0x47/0x1f0
[ 1.066851] kernel_init_freeable+0x1b1/0x243
[ 1.066851] ? rest_init+0xd0/0xd0
[ 1.066851] kernel_init+0xa/0x130
[ 1.066851] ? calculate_sigpending+0x63/0x80
[ 1.066851] ? rest_init+0xd0/0xd0
[ 1.066851] ret_from_fork+0x1f/0x30
[ 1.066851] ---[ end trace 78201497ae74cc91 ]---
To reproduce:
# build kernel
cd linux
cp config-5.2.0-rc1-00018-g26b3a37b928457 .config
make HOSTCC=gcc-4.9 CC=gcc-4.9 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage modules
make HOSTCC=gcc-4.9 CC=gcc-4.9 ARCH=x86_64 INSTALL_MOD_PATH=<mod-install-dir> modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
lkp
10 months, 1 week
[rslib] 4b4f3accd8: INFO:rcu_sched_self-detected_stall_on_CPU
by kernel test robot
FYI, we noticed the following commit (built with gcc-4.9):
commit: 4b4f3accd80304781c648b26ce4d53df082a4087 ("rslib: Add tests for the encoder and decoder")
https://kernel.googlesource.com/pub/scm/linux/kernel/git/torvalds/linux.git master
in testcase: locktorture
with following parameters:
runtime: 300s
test: default
test-description: This torture test consists of creating a number of kernel threads which acquire the lock and hold it for specific amount of time, thus simulating different critical region behaviors.
test-url: https://www.kernel.org/doc/Documentation/locking/locktorture.txt
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 2G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+----------------------------------------------------+----------+------------+
| | v5.2-rc6 | 4b4f3accd8 |
+----------------------------------------------------+----------+------------+
| boot_successes | 10 | 0 |
| boot_failures | 13 | 15 |
| BUG:kernel_reboot-without-warning_in_test_stage | 13 | |
| INFO:rcu_sched_self-detected_stall_on_CPU | 0 | 15 |
| BUG:kernel_hang_in_boot-around-mounting-root_stage | 0 | 2 |
+----------------------------------------------------+----------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <lkp(a)intel.com>
[ 115.336139] rcu: INFO: rcu_sched self-detected stall on CPU
[ 115.336168] rcu: 1-....: (31500 ticks this GP) idle=bea/1/0x4000000000000002 softirq=1099/1099 fqs=10102
[ 115.339505] (t=31502 jiffies g=297 q=94)
[ 115.339505] NMI backtrace for cpu 1
[ 115.339505] CPU: 1 PID: 1 Comm: swapper/0 Not tainted 5.2.0-rc6-00001-g4b4f3accd80304 #1
[ 115.342832] Call Trace:
[ 115.342832] <IRQ>
[ 115.342832] ? dump_stack+0x5e/0x8b
[ 115.342832] ? nmi_cpu_backtrace+0xac/0xb0
[ 115.342832] ? lapic_can_unplug_cpu+0xc0/0xc0
[ 115.342832] ? nmi_trigger_cpumask_backtrace+0xf5/0x130
[ 115.342832] ? rcu_dump_cpu_stacks+0x97/0xc5
[ 115.342832] ? rcu_sched_clock_irq+0x61b/0x820
[ 115.342832] ? update_process_times+0x24/0x40
[ 115.342832] ? tick_periodic+0x2b/0xc0
[ 115.342832] ? tick_periodic+0x5/0xc0
[ 115.342832] ? tick_handle_periodic+0x20/0x60
[ 115.342832] ? smp_apic_timer_interrupt+0x72/0x230
[ 115.342832] ? apic_timer_interrupt+0xf/0x20
[ 115.342832] </IRQ>
[ 115.342832] ? decode_rs16+0xa70/0xba0
[ 115.342832] ? ex_rs_helper+0x431/0x4e0
[ 115.342832] ? exercise_rs+0x51/0x90
[ 115.342832] ? test_rslib_init+0x174/0x222
[ 115.342832] ? sw842_init+0x12e/0x12e
[ 115.342832] ? do_one_initcall+0x66/0x2f4
[ 115.342832] ? rcu_read_lock_sched_held+0x49/0x90
[ 115.342832] ? kernel_init_freeable+0x14e/0x1d4
[ 115.342832] ? rest_init+0x260/0x260
[ 115.342832] ? kernel_init+0xa/0x100
[ 115.342832] ? ret_from_fork+0x24/0x30
[ 116.137768] FAIL: 52901 decoding failures!
[ 116.140836] Testing beyond error correction capacity...
[ 120.925031] FAIL: 12047 silent failures!
[ 120.927456] Testing (89,57)_256 code...
[ 120.928101] Testing up to error correction capacity...
[ 120.929096] Testing correction buffer interface...
[ 122.090172] FAIL: 143306 decoding failures!
[ 122.091517] Testing with caller provided syndrome...
[ 123.630870] FAIL: 142416 decoding failures!
[ 123.634364] Testing in-place interface...
[ 124.823230] FAIL: 54638 decoding failures!
[ 124.824023] Testing beyond error correction capacity...
[ 128.812285] FAIL: 11981 silent failures!
[ 128.815469] Testing (33,1)_256 code...
[ 128.816591] Testing up to error correction capacity...
[ 128.818033] Testing correction buffer interface...
[ 129.652550] FAIL: 143289 decoding failures!
[ 129.653361] Testing with caller provided syndrome...
[ 130.616887] FAIL: 143451 decoding failures!
[ 130.620382] Testing in-place interface...
[ 131.451569] FAIL: 55813 decoding failures!
[ 131.452366] Testing beyond error correction capacity...
[ 132.452832] FAIL: 828 silent failures!
[ 132.455008] Testing (511,478)_512 code...
[ 132.455690] Testing up to error correction capacity...
[ 132.456555] Testing correction buffer interface...
[ 136.053994] FAIL: 180126 decoding failures!
[ 136.057475] Testing with caller provided syndrome...
[ 136.412915] rcu: INFO: rcu_sched detected expedited stalls on CPUs/tasks: { 1-... } 32000 jiffies s: 45 root: 0x2/.
[ 136.415727] rcu: blocking rcu_node structures:
[ 136.417003] Task dump for CPU 1:
[ 136.417918] swapper/0 R running task 13272 1 0 0x80004008
[ 136.419847] Call Trace:
[ 136.420530] ? trace_hardirqs_on_thunk+0x1a/0x1c
[ 136.421803] ? trace_hardirqs_on_thunk+0x1a/0x1c
[ 136.423157] ? lockdep_hardirqs_on+0x148/0x1c0
[ 136.424458] ? trace_hardirqs_on_thunk+0x1a/0x1c
[ 136.425815] ? retint_kernel+0x10/0x10
[ 136.426928] ? encode_rs16+0x112/0x1c0
[ 136.427996] ? decode_rs16+0x4b5/0xba0
[ 136.429120] ? encode_rs16+0x12f/0x1c0
[ 136.430282] ? get_rcw_we+0xc3/0x1e0
[ 136.432472] ? ex_rs_helper+0x17e/0x4e0
[ 136.433119] ? exercise_rs+0x51/0x90
[ 136.433726] ? test_rslib_init+0x174/0x222
[ 136.434395] ? sw842_init+0x12e/0x12e
[ 136.435091] ? do_one_initcall+0x66/0x2f4
[ 136.435742] ? rcu_read_lock_sched_held+0x49/0x90
[ 136.436599] ? kernel_init_freeable+0x14e/0x1d4
[ 136.437347] ? rest_init+0x260/0x260
[ 136.437927] ? kernel_init+0xa/0x100
[ 136.438561] ? ret_from_fork+0x24/0x30
[ 141.451983] FAIL: 180645 decoding failures!
[ 141.452783] Testing in-place interface...
[ 145.253649] FAIL: 33968 decoding failures!
[ 145.255895] Testing beyond error correction capacity...
[ 157.031546] FAIL: 615 silent failures!
[ 157.034597] Testing (392,359)_512 code...
[ 157.035286] Testing up to error correction capacity...
[ 157.036163] Testing correction buffer interface...
[ 160.087885] FAIL: 136129 decoding failures!
[ 160.091092] Testing with caller provided syndrome...
[ 164.580667] FAIL: 136686 decoding failures!
[ 164.585268] Testing in-place interface...
[ 167.906792] FAIL: 40765 decoding failures!
[ 167.908221] Testing beyond error correction capacity...
[ 177.647979] FAIL: 10635 silent failures!
[ 177.648758] Testing (273,240)_512 code...
[ 177.649438] Testing up to error correction capacity...
[ 177.650330] Testing correction buffer interface...
[ 180.025183] FAIL: 127255 decoding failures!
[ 180.026033] Testing with caller provided syndrome...
[ 183.238909] FAIL: 127965 decoding failures!
[ 183.241185] Testing in-place interface...
[ 185.607359] FAIL: 42399 decoding failures!
[ 185.611062] Testing beyond error correction capacity...
[ 193.056046] FAIL: 10640 silent failures!
[ 193.058223] Testing (154,121)_512 code...
[ 193.058910] Testing up to error correction capacity...
[ 193.059822] Testing correction buffer interface...
[ 194.725534] FAIL: 113721 decoding failures!
[ 194.726391] Testing with caller provided syndrome...
[ 196.852541] FAIL: 113704 decoding failures!
[ 196.853360] Testing in-place interface...
[ 198.509609] FAIL: 44881 decoding failures!
[ 198.510949] Testing beyond error correction capacity...
[ 203.828405] FAIL: 10647 silent failures!
[ 203.829176] Testing (34,1)_512 code...
[ 203.829842] Testing up to error correction capacity...
[ 203.830697] Testing correction buffer interface...
[ 204.862634] FAIL: 114591 decoding failures!
[ 204.863944] Testing with caller provided syndrome...
[ 206.040509] FAIL: 113788 decoding failures!
[ 206.041369] Testing in-place interface...
[ 207.098438] FAIL: 47327 decoding failures!
[ 207.099755] Testing beyond error correction capacity...
[ 208.318645] FAIL: 684 silent failures!
[ 208.319433] rslib: test failed
[ 208.324689] gpio_winbond: chip ID at 2e is ffff
[ 208.325557] gpio_winbond: not an our chip
[ 208.326340] gpio_winbond: chip ID at 4e is ffff
[ 208.327099] gpio_winbond: not an our chip
[ 208.328489] IPMI message handler: version 39.2
[ 208.329360] ipmi device interface
[ 208.330680] ipmi_si: IPMI System Interface driver
[ 208.331703] ipmi_si: Unable to find any System Interface(s)
[ 208.332636] ipmi_ssif: IPMI SSIF Interface driver
[ 208.333477] IPMI Watchdog: driver initialized
[ 208.334537] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[ 208.337452] ACPI: Power Button [PWRF]
[ 208.426247] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 208.451294] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[ 208.483196] 00:06: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
[ 208.491826] Non-volatile memory driver v1.3
[ 208.495147] random: get_random_u64 called from arch_rnd+0x24/0x50 with crng_init=0
[ 208.497119] random: get_random_u64 called from load_elf_binary+0x4a8/0x12fe with crng_init=0
[ 208.499651] random: get_random_u64 called from randomize_page+0x54/0x71 with crng_init=0
[ 208.504395] ppdev: user-space parallel port driver
[ 208.507078] dummy-irq: no IRQ given. Use irq=N
[ 208.518834] mtdoops: mtd device (mtddev=name/number) must be supplied
[ 208.523807] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[ 208.525032] e1000: Copyright (c) 1999-2006 Intel Corporation.
[ 208.891017] PCI Interrupt Link [LNKC] enabled at IRQ 11
[ 209.324220] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[ 209.326350] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[ 209.332846] panel: panel driver not yet registered
[ 209.334739] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[ 209.337684] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 209.338564] serio: i8042 AUX port at 0x60,0x64 irq 12
To reproduce:
# build kernel
cd linux
cp config-5.2.0-rc6-00001-g4b4f3accd80304 .config
make HOSTCC=gcc-4.9 CC=gcc-4.9 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage modules
make HOSTCC=gcc-4.9 CC=gcc-4.9 ARCH=x86_64 INSTALL_MOD_PATH=<mod-install-dir> modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
lkp
10 months, 1 week
[iomap] db074436f4: xfstests.generic.446.fail
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: db074436f421967f4f30cfbb6fbc2a728f3e62b3 ("iomap: move the direct IO code into a separate file")
https://kernel.googlesource.com/pub/scm/linux/kernel/git/torvalds/linux.git master
in testcase: xfstests
with following parameters:
disk: 4HDD
fs: xfs
test: generic-group5
test-description: xfstests is a regression test suite for xfs and other files ystems.
test-url: git://git.kernel.org/pub/scm/fs/xfs/xfstests-dev.git
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 4G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <rong.a.chen(a)intel.com>
2019-07-25 10:22:57 export TEST_DIR=/fs/vda
2019-07-25 10:22:57 export TEST_DEV=/dev/vda
2019-07-25 10:22:57 export FSTYP=xfs
2019-07-25 10:22:57 export SCRATCH_MNT=/fs/scratch
2019-07-25 10:22:57 mkdir /fs/scratch -p
2019-07-25 10:22:57 export SCRATCH_DEV=/dev/vdd
2019-07-25 10:22:57 export SCRATCH_LOGDEV=/dev/vdb
meta-data=/dev/vda isize=512 agcount=4, agsize=16777216 blks
= sectsz=512 attr=2, projid32bit=1
= crc=1 finobt=1, sparse=1, rmapbt=0
= reflink=1
data = bsize=4096 blocks=67108864, imaxpct=25
= sunit=0 swidth=0 blks
naming =version 2 bsize=4096 ascii-ci=0, ftype=1
log =internal log bsize=4096 blocks=32768, version=2
= sectsz=512 sunit=0 blks, lazy-count=1
realtime =none extsz=4096 blocks=0, rtextents=0
2019-07-25 10:22:58 export MKFS_OPTIONS=-mreflink=1
2019-07-25 10:22:58 sed "s:^:generic/:" /lkp/lkp/src/pack/xfstests-addon/tests/generic-group5 | grep -F -f merged_ignored_files
ignored by lkp: generic/476
2019-07-25 10:22:58 sed "s:^:generic/:" /lkp/lkp/src/pack/xfstests-addon/tests/generic-group5 | grep -v -F -f merged_ignored_files
2019-07-25 10:22:58 ./check generic/445 generic/446 generic/447 generic/448 generic/449 generic/450 generic/451 generic/452 generic/453 generic/454 generic/456 generic/458 generic/459 generic/460 generic/461 generic/463 generic/464 generic/465 generic/466 generic/467 generic/468 generic/469 generic/471 generic/472 generic/473 generic/474 generic/475 generic/477 generic/478 generic/479 generic/480 generic/481 generic/483 generic/484 generic/485 generic/486 generic/487 generic/488 generic/489 generic/490 generic/491 generic/492 generic/493 generic/494 generic/495 generic/496 generic/497 generic/498 generic/499 generic/500 generic/501 generic/502 generic/503 generic/504 generic/505 generic/506 generic/507 generic/508 generic/509 generic/510 generic/511 generic/512 generic/513 generic/514 generic/515 generic/516 generic/517 generic/518 generic/519 generic/520 generic/522
FSTYP -- xfs (debug)
PLATFORM -- Linux/x86_64 vm-snb-4G-1884f46d9ec3 5.2.0-08446-gdb074436f42196 #1 SMP Thu Jul 25 07:23:33 CST 2019
MKFS_OPTIONS -- -f -mreflink=1 /dev/vdd
MOUNT_OPTIONS -- /dev/vdd /fs/scratch
generic/445 1s
generic/446 [failed, exit status 1]- output mismatch (see /lkp/benchmarks/xfstests/results//generic/446.out.bad)
--- tests/generic/446.out 2019-07-24 19:52:14.000000000 +0800
+++ /lkp/benchmarks/xfstests/results//generic/446.out.bad 2019-07-25 10:23:11.703207317 +0800
@@ -1,2 +1,3 @@
QA output created by 446
Silence is golden
+_check_dmesg: something found in dmesg (see /lkp/benchmarks/xfstests/results//generic/446.dmesg)
...
(Run 'diff -u /lkp/benchmarks/xfstests/tests/generic/446.out /lkp/benchmarks/xfstests/results//generic/446.out.bad' to see the entire diff)
To reproduce:
# build kernel
cd linux
cp config-5.2.0-08446-gdb074436f42196 .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage modules
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 INSTALL_MOD_PATH=<mod-install-dir> modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
Rong Chen
10 months, 1 week
[iommu/dma] b1d2dc009d: can't load the disk
by Rong Chen
FYI, we noticed the following commit (built with gcc-7):
commit: 1b961423158caaae49d3900b7c9c37477bbfa9b3 ("iommu/dma: Fix condition check in iommu_dma_unmap_sg")
https://kernel.googlesource.com/pub/scm/linux/kernel/git/torvalds/linux.git master
on test machine: Intel(R) Xeon(R) CPU E7-8890 v3 @ 2.50GHz with 512G memory
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
If you fix the issue, kindly add following tag
Reported-by: kernel test robot<rong.a.chen(a)intel.com>
Starting Login Service...
Starting LKP bootstrap...
Starting Permit User Sessions...
Starting LSB: Execute the kexec -e command to reboot system...
Starting OpenBSD Secure Shell server...
LKP: HOSTNAME lkp-hsw-4ex1, MAC a0:36:9f:18:d6:a8, kernel 5.2.0-rc2-00025-gb1d2dc0 1, serial console /dev/ttyS0
Starting LSB: Start and stop bmc-watchdog...
Starting /etc/rc.local Compatibility...
[ 36.033591] rc.local[1472]: mkdir: cannot create directory '/var/lock/lkp-boo
[ 40.218548] mgag200 0000:08:00.0: VGA-1: EDID is invalid:
tstrap.lock': Fi
[ 40.219847] ata5: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
[ 40.225126] [00] BAD 00 ff ff ff ff ff ff 00 ff ff ff ff ff ff ff ff
[ 40.225127] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
le exists
[ 40.225127] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 40.225128] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 40.225131] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 40.235427] ata5.00: ATAPI: TEAC DV-W28S-W, 1.0A, max UDMA/100
[ 40.240884] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 40.240885] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 40.240885] [00] BAD ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff ff
[ 40.299838] fbcon: mgag200drmfb (fb0) is primary device
[ 40.299956] Console: switching to colour frame buffer device 128x48
[ 40.301806] ata5.00: configured for UDMA/100
[ 40.306906] scsi 5:0:0:0: CD-ROM TEAC DV-W28S-W 1.0A PQ: 0 ANSI: 5
[ 40.308685] EDAC sbridge: Seeking for: PCI ID 8086:2fa0
[ 40.308711] EDAC sbridge: Seeking for: PCI ID 8086:2fa0
[ 40.308749] EDAC sbridge: Seeking for: PCI ID 8086:2fa0
[ 40.308784] EDAC sbridge: Seeking for: PCI ID 8086:2fa0
[ 40.308820] EDAC sbridge: Seeking for: PCI ID 8086:2fa0
[ 40.308855] EDAC sbridge: Seeking for: PCI ID 8086:2f60
[ 40.308862] EDAC sbridge: Seeking for: PCI ID 8086:2f60
[ 40.308876] EDAC sbridge: Seeking for: PCI ID 8086:2f60
[ 40.308888] EDAC sbridge: Seeking for: PCI ID 8086:2f60
[ 40.308901] EDAC sbridge: Seeking for: PCI ID 8086:2f60
[ 40.308911] EDAC sbridge: Seeking for: PCI ID 8086:2fa8
[ 40.308918] EDAC sbridge: Seeking for: PCI ID 8086:2fa8
[ 40.308930] EDAC sbridge: Seeking for: PCI ID 8086:2fa8
[ 40.308941] EDAC sbridge: Seeking for: PCI ID 8086:2fa8
[ 40.308952] EDAC sbridge: Seeking for: PCI ID 8086:2fa8
[ 40.308962] EDAC sbridge: Seeking for: PCI ID 8086:2f71
[ 40.308969] EDAC sbridge: Seeking for: PCI ID 8086:2f71
[ 40.308980] EDAC sbridge: Seeking for: PCI ID 8086:2f71
[ 40.308991] EDAC sbridge: Seeking for: PCI ID 8086:2f71
[ 40.309005] EDAC sbridge: Seeking for: PCI ID 8086:2f71
[ 40.309016] EDAC sbridge: Seeking for: PCI ID 8086:2faa
[ 40.309023] EDAC sbridge: Seeking for: PCI ID 8086:2faa
[ 40.309036] EDAC sbridge: Seeking for: PCI ID 8086:2faa
[ 40.309048] EDAC sbridge: Seeking for: PCI ID 8086:2faa
[ 40.309062] EDAC sbridge: Seeking for: PCI ID 8086:2faa
[ 40.309073] EDAC sbridge: Seeking for: PCI ID 8086:2fab
[ 40.309082] EDAC sbridge: Seeking for: PCI ID 8086:2fab
[ 40.309116] EDAC sbridge: Seeking for: PCI ID 8086:2fab
[ 40.309128] EDAC sbridge: Seeking for: PCI ID 8086:2fab
[ 40.309141] EDAC sbridge: Seeking for: PCI ID 8086:2fab
[ 40.309151] EDAC sbridge: Seeking for: PCI ID 8086:2fac
[ 40.309159] EDAC sbridge: Seeking for: PCI ID 8086:2fac
iommu/dma
[ 40.309172] EDAC sbridge: Seeking for: PCI ID 8086:2fac
[ 40.309184] EDAC sbridge: Seeking for: PCI ID 8086:2fac
[ 40.309197] EDAC sbridge: Seeking for: PCI ID 8086:2fac
[ 40.309207] EDAC sbridge: Seeking for: PCI ID 8086:2fad
[ 40.309215] EDAC sbridge: Seeking for: PCI ID 8086:2fad
[ 40.309227] EDAC sbridge: Seeking for: PCI ID 8086:2fad
[ 40.309239] EDAC sbridge: Seeking for: PCI ID 8086:2fad
[ 40.309251] EDAC sbridge: Seeking for: PCI ID 8086:2fad
[ 40.309261] EDAC sbridge: Seeking for: PCI ID 8086:2f68
[ 40.309271] EDAC sbridge: Seeking for: PCI ID 8086:2f68
[ 40.309283] EDAC sbridge: Seeking for: PCI ID 8086:2f68
[ 40.309296] EDAC sbridge: Seeking for: PCI ID 8086:2f68
[ 40.309314] EDAC sbridge: Seeking for: PCI ID 8086:2f68
[ 40.309322] EDAC sbridge: Seeking for: PCI ID 8086:2f79
[ 40.309332] EDAC sbridge: Seeking for: PCI ID 8086:2f79
[ 40.309344] EDAC sbridge: Seeking for: PCI ID 8086:2f79
[ 40.309357] EDAC sbridge: Seeking for: PCI ID 8086:2f79
[ 40.309369] EDAC sbridge: Seeking for: PCI ID 8086:2f79
[ 40.309377] EDAC sbridge: Seeking for: PCI ID 8086:2f6a
[ 40.309387] EDAC sbridge: Seeking for: PCI ID 8086:2f6a
[ 40.309399] EDAC sbridge: Seeking for: PCI ID 8086:2f6a
[ 40.309411] EDAC sbridge: Seeking for: PCI ID 8086:2f6a
[ 40.309424] EDAC sbridge: Seeking for: PCI ID 8086:2f6a
[ 40.309432] EDAC sbridge: Seeking for: PCI ID 8086:2f6b
[ 40.309442] EDAC sbridge: Seeking for: PCI ID 8086:2f6b
[ 40.309454] EDAC sbridge: Seeking for: PCI ID 8086:2f6b
[ 40.309466] EDAC sbridge: Seeking for: PCI ID 8086:2f6b
[ 40.309479] EDAC sbridge: Seeking for: PCI ID 8086:2f6b
[ 40.309487] EDAC sbridge: Seeking for: PCI ID 8086:2f6c
[ 40.309497] EDAC sbridge: Seeking for: PCI ID 8086:2f6c
[ 40.309509] EDAC sbridge: Seeking for: PCI ID 8086:2f6c
[ 40.309522] EDAC sbridge: Seeking for: PCI ID 8086:2f6c
[ 40.309534] EDAC sbridge: Seeking for: PCI ID 8086:2f6c
[ 40.309542] EDAC sbridge: Seeking for: PCI ID 8086:2f6d
[ 40.309552] EDAC sbridge: Seeking for: PCI ID 8086:2f6d
[ 40.309565] EDAC sbridge: Seeking for: PCI ID 8086:2f6d
[ 40.309576] EDAC sbridge: Seeking for: PCI ID 8086:2f6d
[ 40.309589] EDAC sbridge: Seeking for: PCI ID 8086:2f6d
[ 40.309596] EDAC sbridge: Seeking for: PCI ID 8086:2ffc
[ 40.309603] EDAC sbridge: Seeking for: PCI ID 8086:2ffc
[ 40.309615] EDAC sbridge: Seeking for: PCI ID 8086:2ffc
[ 40.309627] EDAC sbridge: Seeking for: PCI ID 8086:2ffc
[ 40.309640] EDAC sbridge: Seeking for: PCI ID 8086:2ffc
[ 40.309651] EDAC sbridge: Seeking for: PCI ID 8086:2ffd
[ 40.309658] EDAC sbridge: Seeking for: PCI ID 8086:2ffd
[ 40.309670] EDAC sbridge: Seeking for: PCI ID 8086:2ffd
[ 40.309683] EDAC sbridge: Seeking for: PCI ID 8086:2ffd
[ 40.309695] EDAC sbridge: Seeking for: PCI ID 8086:2ffd
[ 40.309707] EDAC sbridge: Seeking for: PCI ID 8086:2fbd
[ 40.309716] EDAC sbridge: Seeking for: PCI ID 8086:2fbd
[ 40.309728] EDAC sbridge: Seeking for: PCI ID 8086:2fbd
[ 40.309741] EDAC sbridge: Seeking for: PCI ID 8086:2fbd
[ 40.309753] EDAC sbridge: Seeking for: PCI ID 8086:2fbd
[ 40.309762] EDAC sbridge: Seeking for: PCI ID 8086:2fbf
[ 40.309771] EDAC sbridge: Seeking for: PCI ID 8086:2fbf
[ 40.309783] EDAC sbridge: Seeking for: PCI ID 8086:2fbf
[ 40.309796] EDAC sbridge: Seeking for: PCI ID 8086:2fbf
[ 40.309808] EDAC sbridge: Seeking for: PCI ID 8086:2fbf
[ 40.309817] EDAC sbridge: Seeking for: PCI ID 8086:2fb9
[ 40.309828] EDAC sbridge: Seeking for: PCI ID 8086:2fb9
[ 40.309840] EDAC sbridge: Seeking for: PCI ID 8086:2fb9
[ 40.309853] EDAC sbridge: Seeking for: PCI ID 8086:2fb9
[ 40.309865] EDAC sbridge: Seeking for: PCI ID 8086:2fb9
[ 40.309872] EDAC sbridge: Seeking for: PCI ID 8086:2fbb
[ 40.309883] EDAC sbridge: Seeking for: PCI ID 8086:2fbb
[ 40.309896] EDAC sbridge: Seeking for: PCI ID 8086:2fbb
[ 40.309908] EDAC sbridge: Seeking for: PCI ID 8086:2fbb
[ 40.309921] EDAC sbridge: Seeking for: PCI ID 8086:2fbb
[ 40.310242] EDAC MC0: Giving out device to module sb_edac controller Haswell SrcID#3_Ha#0: DEV 0000:ff:12.0 (INTERRUPT)
[ 40.310543] EDAC MC1: Giving out device to module sb_edac controller Haswell SrcID#2_Ha#0: DEV 0000:bf:12.0 (INTERRUPT)
[ 40.310836] EDAC MC2: Giving out device to module sb_edac controller Haswell SrcID#1_Ha#0: DEV 0000:7f:12.0 (INTERRUPT)
[ 40.311148] EDAC MC3: Giving out device to module sb_edac controller Haswell SrcID#0_Ha#0: DEV 0000:3f:12.0 (INTERRUPT)
[ 40.311368] EDAC MC4: Giving out device to module sb_edac controller Haswell SrcID#3_Ha#1: DEV 0000:ff:12.4 (INTERRUPT)
[ 40.311563] EDAC MC5: Giving out device to module sb_edac controller Haswell SrcID#2_Ha#1: DEV 0000:bf:12.4 (INTERRUPT)
[ 40.311757] EDAC MC6: Giving out device to module sb_edac controller Haswell SrcID#1_Ha#1: DEV 0000:7f:12.4 (INTERRUPT)
[ 40.311956] EDAC MC7: Giving out device to module sb_edac controller Haswell SrcID#0_Ha#1: DEV 0000:3f:12.4 (INTERRUPT)
[ 40.311957] EDAC sbridge: Ver: 1.1.2
[ 40.320893] intel_rapl: Found RAPL domain package
[ 40.320898] intel_rapl: Found RAPL domain dram
[ 40.320900] intel_rapl: DRAM domain energy unit 15300pj
[ 40.323496] intel_rapl: Found RAPL domain package
[ 40.323501] intel_rapl: Found RAPL domain dram
[ 40.323503] intel_rapl: DRAM domain energy unit 15300pj
[ 40.324065] intel_rapl: Found RAPL domain package
[ 40.324071] intel_rapl: Found RAPL domain dram
[ 40.324073] intel_rapl: DRAM domain energy unit 15300pj
[ 40.324534] intel_rapl: Found RAPL domain package
[ 40.324539] intel_rapl: Found RAPL domain dram
[ 40.324542] intel_rapl: DRAM domain energy unit 15300pj
[ 40.374408] scsi 5:0:0:0: Attached scsi generic sg0 type 5
[ 40.417197] sr 5:0:0:0: [sr0] scsi3-mmc drive: 24x/24x writer dvd-ram cd/rw xa/form2 cdda tray
[ 40.417199] cdrom: Uniform CD-ROM driver Revision: 3.20
[ 40.419939] sr 5:0:0:0: Attached scsi CD-ROM sr0
[ 41.046795] mpt2sas_cm0: diag reset: SUCCESS
[ 41.052527] mgag200 0000:08:00.0: fb0: mgag200drmfb frame buffer device
Starting Load CPU micro
[ 41.358171] [drm] Initialized mgag200 1.0.0 20110418 for 0000:08:00.0 on minor 0
code update...
Starting System Logging Service...
0m] Started Syst
[ 41.399695] mpt2sas_cm0: failure at drivers/scsi/mpt3sas/mpt3sas_scsih.c:10522/_scsih_probe()!
em Logging Service.
Starting LSB: Load kernel image with kexec...
[ 44.527222] Kernel tests: Boot OK!
[ 44.527225]
[ 48.521365] install debs round one: dpkg -i --force-confdef --force-depends /opt/deb/ntpdate_1%3a4.2.8p10+dfsg-3+deb9u2_amd64.deb
[ 48.521368]
[ 48.538114] /opt/deb/python2.7_2.7.13-2+deb9u3_amd64.deb
[ 48.538116]
[ 48.547544] /opt/deb/libpython2.7-stdlib_2.7.13-2+deb9u3_amd64.deb
[ 48.547548]
[ 48.557987] /opt/deb/python2.7-minimal_2.7.13-2+deb9u3_amd64.deb
[ 48.557990]
[ 48.568234] /opt/deb/libpython2.7-minimal_2.7.13-2+deb9u3_amd64.deb
[ 48.568235]
[ 48.578670] /opt/deb/libpython2.7_2.7.13-2+deb9u3_amd64.deb
[ 48.578673]
[ 48.588134] /opt/deb/sysstat_11.4.3-2_amd64.deb
[ 48.588135]
[ 48.596519] /opt/deb/gawk_1%3a4.1.4+dfsg-1_amd64.deb
[ 48.596521]
[ 48.605583] Selecting previously unselected package ntpdate.
[ 48.605586]
[ 48.615955] (Reading database ... 16195 files and directories currently installed.)
[ 48.615958]
[ 48.628562] Preparing to unpack .../ntpdate_1%3a4.2.8p10+dfsg-3+deb9u2_amd64.deb ...
[ 48.628565]
[ 48.640842] Unpacking ntpdate (1:4.2.8p10+dfsg-3+deb9u2) ...
[ 48.640845]
[ 48.651117] Preparing to unpack .../python2.7_2.7.13-2+deb9u3_amd64.deb ...
[ 48.651119]
[ 48.662900] Unpacking python2.7 (2.7.13-2+deb9u3) over (2.7.13-2+deb9u2) ...
[ 48.662905]
[ 48.674907] Preparing to unpack .../libpython2.7-stdlib_2.7.13-2+deb9u3_amd64.deb ...
[ 48.674912]
[ 48.685559] random: crng init done
[ 48.688065] Unpacking libpython2.7-stdlib:amd64 (2.7.13-2+deb9u3) over (2.7.13-2+deb9u2) ...
[ 48.688070]
[ 48.691528] random: 7 urandom warning(s) missed due to ratelimiting
[ 48.712690] Preparing to unpack .../python2.7-minimal_2.7.13-2+deb9u3_amd64.deb ...
[ 48.712693]
[ 48.725540] Unpacking python2.7-minimal (2.7.13-2+deb9u3) over (2.7.13-2+deb9u2) ...
[ 48.725546]
[ 48.738565] Preparing to unpack .../libpython2.7-minimal_2.7.13-2+deb9u3_amd64.deb ...
[ 48.738569]
[ 48.751939] Unpacking libpython2.7-minimal:amd64 (2.7.13-2+deb9u3) over (2.7.13-2+deb9u2) ...
[ 48.751941]
[ 48.765516] Selecting previously unselected package libpython2.7:amd64.
[ 48.765517]
[ 48.777059] Preparing to unpack .../libpython2.7_2.7.13-2+deb9u3_amd64.deb ...
[ 48.777060]
[ 48.788991] Unpacking libpython2.7:amd64 (2.7.13-2+deb9u3) ...
[ 48.788997]
[ 48.799292] Selecting previously unselected package sysstat.
[ 48.799293]
[ 48.809615] Preparing to unpack .../deb/sysstat_11.4.3-2_amd64.deb ...
[ 48.809618]
[ 48.820387] Unpacking sysstat (11.4.3-2) ...
[ 48.820389]
[ 48.828886] Selecting previously unselected package gawk.
[ 48.828892]
[ 48.838913] Preparing to unpack .../gawk_1%3a4.1.4+dfsg-1_amd64.deb ...
[ 48.838918]
[ 48.849806] Unpacking gawk (1:4.1.4+dfsg-1) ...
[ 48.849807]
[ 48.858691] Setting up ntpdate (1:4.2.8p10+dfsg-3+deb9u2) ...
[ 48.858694]
[ 48.869081] Setting up libpython2.7-minimal:amd64 (2.7.13-2+deb9u3) ...
[ 48.869084]
[ 48.879966] Setting up sysstat (11.4.3-2) ...
[ 48.879967]
[ 48.888376] Setting up gawk (1:4.1.4+dfsg-1) ...
[ 48.888380]
[ 48.897503] Setting up libpython2.7-stdlib:amd64 (2.7.13-2+deb9u3) ...
[ 48.897505]
[ 48.908595] Setting up python2.7-minimal (2.7.13-2+deb9u3) ...
[ 48.908598]
[ 48.918941] Setting up libpython2.7:amd64 (2.7.13-2+deb9u3) ...
[ 48.918945]
[ 48.929200] Setting up python2.7 (2.7.13-2+deb9u3) ...
[ 48.929202]
[ 48.938602] Processing triggers for mime-support (3.60) ...
[ 48.938605]
[ 48.948663] Processing triggers for libc-bin (2.24-11+deb9u3) ...
[ 48.948664]
[ 48.959229] Processing triggers for systemd (232-25+deb9u2) ...
[ 48.959231]
[ 49.729064] 25 Jul 16:02:52 ntpdate[1803]: step time server 192.168.1.1 offset 28724.486990 sec
[ 49.729069]
[ 81.657115] can't load the disk LABEL=LKP-ROOTFS, skip testing...
Thanks,
Rong Chen
10 months, 1 week
56cbb429d9: ltp.fs_fill.fail
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: 56cbb429d911991170fe867b4bba14f0efed5829 ("switch the remnants of releasing the mountpoint away from fs_pin")
https://kernel.googlesource.com/pub/scm/linux/kernel/git/torvalds/linux.git master
in testcase: ltp
with following parameters:
disk: 1HDD
fs: btrfs
test: fs-03
test-description: The LTP testsuite contains a collection of tools for testing the Linux kernel and related features.
test-url: http://linux-test-project.github.io/
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 8G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <rong.a.chen(a)intel.com>
<<<test_start>>>
tag=fs_fill stime=1563953948
cmdline="fs_fill"
contacts=""
analysis=exit
<<<test_output>>>
tst_device.c:231: INFO: Using test device LTP_DEV='/dev/loop0'
tst_supported_fs_types.c:60: INFO: Kernel supports ext2
tst_supported_fs_types.c:44: INFO: mkfs.ext2 does exist
tst_supported_fs_types.c:60: INFO: Kernel supports ext3
tst_supported_fs_types.c:44: INFO: mkfs.ext3 does exist
tst_supported_fs_types.c:60: INFO: Kernel supports ext4
tst_supported_fs_types.c:44: INFO: mkfs.ext4 does exist
tst_supported_fs_types.c:60: INFO: Kernel supports xfs
tst_supported_fs_types.c:44: INFO: mkfs.xfs does exist
tst_supported_fs_types.c:60: INFO: Kernel supports btrfs
tst_supported_fs_types.c:44: INFO: mkfs.btrfs does exist
tst_supported_fs_types.c:60: INFO: Kernel supports vfat
tst_supported_fs_types.c:44: INFO: mkfs.vfat does exist
tst_supported_fs_types.c:92: INFO: FUSE does support exfat
tst_supported_fs_types.c:44: INFO: mkfs.exfat does exist
tst_supported_fs_types.c:92: INFO: FUSE does support ntfs
tst_supported_fs_types.c:44: INFO: mkfs.ntfs does exist
tst_test.c:1161: INFO: Testing on ext2
tst_mkfs.c:90: INFO: Formatting /dev/loop0 with ext2 opts='' extra opts=''
mke2fs 1.43.4 (31-Jan-2017)
tst_test.c:1100: INFO: Timeout per run is 0h 05m 00s
fs_fill.c:103: INFO: Running 4 writer threads
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file1
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file1
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file3
fs_fill.c:87: PASS: Got 16 ENOSPC runtime 1000ms
tst_test.c:1161: INFO: Testing on ext3
tst_mkfs.c:90: INFO: Formatting /dev/loop0 with ext3 opts='' extra opts=''
mke2fs 1.43.4 (31-Jan-2017)
tst_test.c:1100: INFO: Timeout per run is 0h 05m 00s
fs_fill.c:103: INFO: Running 4 writer threads
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file1
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file2
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file2
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file1
fs_fill.c:87: PASS: Got 13 ENOSPC runtime 1000ms
tst_test.c:1161: INFO: Testing on ext4
tst_mkfs.c:90: INFO: Formatting /dev/loop0 with ext4 opts='' extra opts=''
mke2fs 1.43.4 (31-Jan-2017)
tst_test.c:1100: INFO: Timeout per run is 0h 05m 00s
fs_fill.c:103: INFO: Running 4 writer threads
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file1
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file2
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file1
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
fs_fill.c:87: PASS: Got 20 ENOSPC runtime 1000ms
tst_test.c:1161: INFO: Testing on xfs
tst_mkfs.c:90: INFO: Formatting /dev/loop0 with xfs opts='' extra opts=''
tst_test.c:1100: INFO: Timeout per run is 0h 05m 00s
fs_fill.c:103: INFO: Running 4 writer threads
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file2
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file1
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file2
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:36: INFO: open(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file1
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:36: INFO: open(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file2
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
fs_fill.c:87: PASS: Got 32 ENOSPC runtime 1000ms
tst_test.c:1161: INFO: Testing on btrfs
tst_mkfs.c:90: INFO: Formatting /dev/loop0 with btrfs opts='' extra opts=''
tst_test.c:1100: INFO: Timeout per run is 0h 05m 00s
fs_fill.c:103: INFO: Running 4 writer threads
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file0
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file2
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file0
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file2
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file1
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file2
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file2
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file1
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread3/file2
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread4/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread2/file0
tst_fill_fs.c:49: INFO: write(): ENOSPC
fs_fill.c:53: INFO: Unlinking mntpoint/thread1/file0
fs_fill.c:87: PASS: Got 33 ENOSPC runtime 1000ms
tst_test.c:1161: INFO: Testing on vfat
tst_mkfs.c:90: INFO: Formatting /dev/loop0 with vfat opts='' extra opts=''
mkfs.vfat: unable to open /dev/loop0: Device or resource busy
tst_mkfs.c:101: BROK: mkfs.vfat:1: tst_test.c failed with 741
Summary:
passed 5
failed 0
skipped 0
warnings 0
<<<execution_status>>>
initiation_status="ok"
duration=8 termination_type=exited termination_id=2 corefile=no
To reproduce:
# build kernel
cd linux
cp config-5.2.0-rc5-00011-g56cbb429d91199 .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage modules
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 INSTALL_MOD_PATH=<mod-install-dir> modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
Rong Chen
10 months, 1 week
[f2fs] 3d03243e45: WARNING:at_mm/page_alloc.c:#__alloc_pages_nodemask
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: 3d03243e4536c00d6fdc3ffc60e4bdddc6fdc31c ("f2fs: allocate memory in batch in build_sit_info()")
https://kernel.googlesource.com/pub/scm/linux/kernel/git/next/linux-next.git master
in testcase: xfstests
with following parameters:
disk: 4HDD
fs: f2fs
test: generic-054
test-description: xfstests is a regression test suite for xfs and other files ystems.
test-url: git://git.kernel.org/pub/scm/fs/xfs/xfstests-dev.git
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 2G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+----------------------------------------------------+------------+------------+
| | d5e5efa250 | 3d03243e45 |
+----------------------------------------------------+------------+------------+
| boot_successes | 32 | 2 |
| boot_failures | 38 | 88 |
| BUG:kernel_reboot-without-warning_in_test_stage | 38 | |
| WARNING:at_mm/page_alloc.c:#__alloc_pages_nodemask | 0 | 88 |
| RIP:__alloc_pages_nodemask | 0 | 88 |
+----------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <rong.a.chen(a)intel.com>
[ 17.942328] WARNING: CPU: 0 PID: 696 at mm/page_alloc.c:4711 __alloc_pages_nodemask+0x1d1/0x340
[ 17.944450] Modules linked in: dm_mod f2fs sr_mod cdrom sg ata_generic pata_acpi crct10dif_pclmul crc32_pclmul crc32c_intel ghash_clmulni_intel bochs_drm ttm ppdev snd_pcm drm_kms_helper ata_piix snd_timer snd aesni_intel syscopyarea libata crypto_simd sysfillrect sysimgblt cryptd glue_helper soundcore joydev pcspkr serio_raw fb_sys_fops drm i2c_piix4 floppy parport_pc parport ip_tables
[ 17.951060] CPU: 0 PID: 696 Comm: mount Not tainted 5.2.0-07769-g3d03243e4536c0 #2
[ 17.952576] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 17.954141] RIP: 0010:__alloc_pages_nodemask+0x1d1/0x340
[ 17.955149] Code: ff ff ff 65 48 8b 04 25 c0 6b 01 00 48 05 48 0c 00 00 41 be 01 00 00 00 48 89 44 24 08 e9 02 ff ff ff 81 e7 00 20 00 00 75 02 <0f> 0b 45 31 f6 eb 95 44 8b 64 24 18 65 8b 05 5c ba da 5d 89 c0 48
[ 17.958636] RSP: 0018:ffffbc688039fbc8 EFLAGS: 00010246
[ 17.959626] RAX: 0000000000000000 RBX: 00000000017ee3c0 RCX: 0000000000000000
[ 17.960970] RDX: 0000000000000000 RSI: 000000000000000d RDI: 0000000000000000
[ 17.962307] RBP: 00000000017ee3c0 R08: ffff9482feb18000 R09: fffff6f701e97400
[ 17.963644] R10: fffff6f701e97300 R11: 0000000000000000 R12: 0000000000000dc0
[ 17.965007] R13: ffff9482ea464c80 R14: 000000000000000d R15: ffff9482ff6660a0
[ 17.966353] FS: 00007fc57d449e40(0000) GS:ffff9482ea400000(0000) knlGS:0000000000000000
[ 17.967886] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 17.968981] CR2: 000055e536756870 CR3: 000000005a150000 CR4: 00000000000406f0
[ 17.970321] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 17.971661] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 17.973009] Call Trace:
[ 17.973508] kmalloc_order+0x14/0x40
[ 17.974217] kmalloc_order_trace+0x1d/0xb0
[ 17.975015] f2fs_kmalloc+0xe/0x30 [f2fs]
[ 17.976137] f2fs_build_segment_manager+0x254/0x1b40 [f2fs]
[ 17.977198] ? validate_checkpoint+0xcd/0x390 [f2fs]
[ 17.978148] ? f2fs_get_valid_checkpoint+0x3cd/0x760 [f2fs]
[ 17.979203] f2fs_fill_super+0xea6/0x1e30 [f2fs]
[ 17.980120] ? f2fs_commit_super+0x190/0x190 [f2fs]
[ 17.981048] mount_bdev+0x173/0x1b0
[ 17.981719] legacy_get_tree+0x27/0x40
[ 17.982438] vfs_get_tree+0x25/0xf0
[ 17.983108] do_mount+0x683/0x9b0
[ 17.983747] ? memdup_user+0x4b/0x70
[ 17.984458] ksys_mount+0x80/0xd0
[ 17.985098] __x64_sys_mount+0x21/0x30
[ 17.985818] do_syscall_64+0x5b/0x1d0
[ 17.986521] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 17.987479] RIP: 0033:0x7fc57cb0648a
[ 17.988181] Code: 48 8b 0d 11 fa 2a 00 f7 d8 64 89 01 48 83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 49 89 ca b8 a5 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d de f9 2a 00 f7 d8 64 89 01 48
[ 17.993280] RSP: 002b:00007ffc9a066418 EFLAGS: 00000206 ORIG_RAX: 00000000000000a5
[ 17.995535] RAX: ffffffffffffffda RBX: 0000557daab115c0 RCX: 00007fc57cb0648a
[ 17.997695] RDX: 0000557daab117a0 RSI: 0000557daab117e0 RDI: 0000557daab117c0
[ 17.999836] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000020
[ 18.001965] R10: 00000000c0ed0000 R11: 0000000000000206 R12: 0000557daab117c0
[ 18.004210] R13: 0000557daab117a0 R14: 0000000000000000 R15: 00000000ffffffff
[ 18.007803] ---[ end trace 7421db5ba1f0bb3c ]---
To reproduce:
# build kernel
cd linux
cp config-5.2.0-07769-g3d03243e4536c0 .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage modules
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 INSTALL_MOD_PATH=<mod-install-dir> modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
Rong Chen
10 months, 2 weeks
[mm] [confidence: ] e49f335ded: BUG:sleeping_function_called_from_invalid_context_at_mm/util.c
by kernel test robot
FYI, we noticed the following commit (built with gcc-7):
commit: e49f335dedc18a2ee0ac7791134b282abd9c857f ("mm: check for sleepable context in kvfree")
https://kernel.googlesource.com/pub/scm/linux/kernel/git/jlayton/linux.git mm-5.4
in testcase: xfstests
with following parameters:
disk: 4HDD
fs: f2fs
test: generic-quick3
test-description: xfstests is a regression test suite for xfs and other files ystems.
test-url: git://git.kernel.org/pub/scm/fs/xfs/xfstests-dev.git
on test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 2G
caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):
+----------------------------------------------------------------+------------+------------+
| | 7b5cf701ea | e49f335ded |
+----------------------------------------------------------------+------------+------------+
| boot_successes | 67 | 0 |
| boot_failures | 96 | 100 |
| WARNING:at_arch/x86/kernel/rtc.c:#mach_get_cmos_time | 4 | |
| RIP:mach_get_cmos_time | 4 | |
| BUG:kernel_reboot-without-warning_in_test_stage | 55 | |
| INFO:rcu_sched_self-detected_stall_on_CPU | 1 | |
| RIP:__memcpy | 1 | |
| BUG:kernel_hang_in_boot_stage | 2 | |
| BUG:kernel_NULL_pointer_dereference,address | 35 | |
| Oops:#[##] | 35 | |
| RIP:dma_direct_max_mapping_size | 35 | |
| Kernel_panic-not_syncing:Fatal_exception | 35 | |
| BUG:sleeping_function_called_from_invalid_context_at_mm/util.c | 0 | 100 |
+----------------------------------------------------------------+------------+------------+
If you fix the issue, kindly add following tag
Reported-by: kernel test robot <rong.a.chen(a)intel.com>
[ 24.662886] BUG: sleeping function called from invalid context at mm/util.c:485
[ 24.665429] in_atomic(): 1, irqs_disabled(): 1, pid: 1466, name: mount
[ 24.667323] CPU: 1 PID: 1466 Comm: mount Not tainted 5.3.0-rc1-00057-ge49f335dedc18a #1
[ 24.669343] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
[ 24.671642] Call Trace:
[ 24.673336] dump_stack+0x5c/0x7b
[ 24.674753] ___might_sleep+0xf1/0x110
[ 24.676063] kvfree+0x61/0x80
[ 24.677374] f2fs_build_node_manager+0x5f7/0x720 [f2fs]
[ 24.678973] f2fs_fill_super+0xeb8/0x1e30 [f2fs]
[ 24.680544] ? f2fs_commit_super+0x190/0x190 [f2fs]
[ 24.682097] mount_bdev+0x173/0x1b0
[ 24.683321] legacy_get_tree+0x27/0x40
[ 24.684820] vfs_get_tree+0x25/0xf0
[ 24.686029] do_mount+0x691/0x9c0
[ 24.687412] ksys_mount+0x80/0xd0
[ 24.688667] __x64_sys_mount+0x21/0x30
[ 24.689889] do_syscall_64+0x5b/0x1f0
[ 24.691325] entry_SYSCALL_64_after_hwframe+0x44/0xa9
[ 24.692824] RIP: 0033:0x7f9b2f22c48a
[ 24.694212] Code: 48 8b 0d 11 fa 2a 00 f7 d8 64 89 01 48 83 c8 ff c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 49 89 ca b8 a5 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 01 c3 48 8b 0d de f9 2a 00 f7 d8 64 89 01 48
[ 24.698836] RSP: 002b:00007ffd44d022b8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
[ 24.700896] RAX: ffffffffffffffda RBX: 000055d6197fd080 RCX: 00007f9b2f22c48a
[ 24.702940] RDX: 000055d6197fd260 RSI: 000055d6197fd2e0 RDI: 000055d6197fd2c0
[ 24.704928] RBP: 0000000000000000 R08: 000055d6197fd280 R09: 000000000000002f
[ 24.706866] R10: 00000000c0ed0001 R11: 0000000000000246 R12: 000055d6197fd2c0
[ 24.708988] R13: 000055d6197fd260 R14: 00007ffd44d02438 R15: 000055d6197fd2c0
[ 24.719635] F2FS-fs (vdd): Need to recover fsync data, but write access unavailable
[ 24.867539] F2FS-fs (vdd): recover fsync data on readonly fs
[ 24.873295] F2FS-fs (vdd): Mounted with checkpoint version = 23c9d0ea
[ 24.991834] F2FS-fs (vdd): recover fsync data on readonly fs
[ 24.999918] F2FS-fs (vdd): Mounted with checkpoint version = 23c9d0ea
[ 25.125365] run fstests generic/051 at 2019-07-24 16:42:56
[ 25.261578] result_service=inn:/result, RESULT_MNT=/inn/result, RESULT_ROOT=/inn/result/xfstests/4HDD-f2fs-generic-quick3/vm-snb-2G/debian-x86_64-2018-04-03.cgz/x86_64-rhel-7.6/gcc-7/e49f335dedc18a2ee0ac7791134b282abd9c857f/3
[ 25.261581]
[ 25.270070] mount.nfs: try 1 time... mount.nfs -o vers=3 inn:/result /inn/result
[ 25.270074]
[ 25.279356] run-job /lkp/jobs/scheduled/vm-snb-2G-3631a88de49b/xfstests-4HDD-f2fs-generic-quick3-debian-x86_64-2018-04-03.cgz-e49f3-20190724-6050-17o56r9-1.yaml
[ 25.279358]
[ 25.347888] F2FS-fs (vda): Found nat_bits in checkpoint
[ 25.512446] F2FS-fs (vda): Mounted with checkpoint version = 58eca5b1
[ 26.041459] F2FS-fs (vdd): Found nat_bits in checkpoint
[ 26.208391] F2FS-fs (vdd): Mounted with checkpoint version = 7f40f917
[ 26.749255] F2FS-fs (vdd): Found nat_bits in checkpoint
[ 26.907409] F2FS-fs (vdd): Mounted with checkpoint version = 2d85b69f
[ 27.917438] /usr/bin/wget -q --timeout=1800 --tries=1 --local-encoding=UTF-8 http://inn:80/~lkp/cgi-bin/lkp-jobfile-append-var?job_file=/lkp/jobs/sche... -O /dev/null
[ 27.917441]
[ 27.925520] target ucode:
[ 27.925522]
[ 27.932165] 2019-07-24 16:42:45 dmsetup remove_all
[ 27.932168]
[ 27.940098] 2019-07-24 16:42:45 wipefs -a --force /dev/vda
[ 27.940102]
[ 27.944768] 2019-07-24 16:42:45 wipefs -a --force /dev/vdb
[ 27.944771]
[ 27.949325] 2019-07-24 16:42:45 wipefs -a --force /dev/vdc
[ 27.949327]
[ 27.954154] 2019-07-24 16:42:45 wipefs -a --force /dev/vdd
[ 27.954156]
[ 27.959379] 2019-07-24 16:42:45 mkfs -t f2fs /dev/vdc
[ 27.959381]
[ 27.963492] 2019-07-24 16:42:45 mkfs -t f2fs /dev/vda
[ 27.963494]
[ 27.967545] 2019-07-24 16:42:45 mkfs -t f2fs /dev/vdd
[ 27.967547]
[ 27.971238] 2019-07-24 16:42:45 mkfs -t f2fs /dev/vdb
[ 27.971240]
[ 27.973770]
[ 27.977245] F2FS-tools: mkfs.f2fs Ver: 1.12.0 (2019-05-20)
[ 27.977247]
[ 27.979883]
[ 27.982161] Info: Disable heap-based policy
[ 27.982162]
[ 27.985739] Info: Debug level = 0
[ 27.985741]
[ 27.988669] Info: Trim is enabled
[ 27.988672]
[ 27.991478] Info: Segments per section = 1
[ 27.991480]
[ 27.994821] Info: Sections per zone = 1
[ 27.994823]
[ 27.997439] Info: sector size = 512
[ 27.997441]
[ 28.000862] Info: total sectors = 536870912 (262144 MB)
[ 28.000864]
[ 28.004563] Info: zone aligned segment0 blkaddr: 512
[ 28.004565]
[ 28.007679] Info: format version with
[ 28.007681]
[ 28.013886] "Linux version 5.3.0-rc1-00057-ge49f335dedc18a (kbuild@108c9eb08fcc) (gcc version 7.4.0 (Debian 7.4.0-10)) #1 SMP Wed Jul 24 03:51:28 CST 2019"
[ 28.013888]
[ 28.023110] Info: [/dev/vda] Discarding device
[ 28.023112]
[ 28.027786] Info: This device doesn't support BLKSECDISCARD
[ 28.027789]
[ 28.037600] Info: This device doesn't support BLKDISCARD
[ 28.037603]
[ 28.042607] Info: Overprovision ratio = 0.390%
[ 28.042609]
[ 28.046632] Info: Overprovision segments = 1027 (GC reserved = 520)
[ 28.046634]
To reproduce:
# build kernel
cd linux
cp config-5.3.0-rc1-00057-ge49f335dedc18a .config
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 olddefconfig prepare modules_prepare bzImage modules
make HOSTCC=gcc-7 CC=gcc-7 ARCH=x86_64 INSTALL_MOD_PATH=<mod-install-dir> modules_install
cd <mod-install-dir>
find lib/ | cpio -o -H newc --quiet | gzip > modules.cgz
git clone https://github.com/intel/lkp-tests.git
cd lkp-tests
bin/lkp qemu -k <bzImage> -m modules.cgz job-script # job-script is attached in this email
Thanks,
Rong Chen
10 months, 2 weeks