[linux-next:master] [x86/crc32] 55d1ecceb8: INFO:task_blocked_for_more_than#seconds

[kernel test robot <oliver.sang@intel.com>]

Hello,


we don't have enough knowledge how to connect the changes in 55d1ecceb8 with
the random issue we found. we tried rebuild kernel and rerun more till to 200
times for both 55d1ecceb8 and its parent, still observed the issue on 55d1ecceb8
but clean on parent.

1e6b72e60a5a16dd 55d1ecceb8d6a5c9ceff7c65280
---------------- ---------------------------
       fail:runs  %reproduction    fail:runs
           |             |             |
           :200         29%          58:200   dmesg.INFO:task_blocked_for_more_than#seconds

the config diff is:

--- /pkg/linux/i386-randconfig-011-20241220/gcc-12/1e6b72e60a5a16ddda9c0669da7538f497838d0a/.config     2024-12-24 17:07:56.621157765 +0800
+++ /pkg/linux/i386-randconfig-011-20241220/gcc-12/55d1ecceb8d6a5c9ceff7c6528075bd0ce4e8366/.config     2024-12-24 15:40:46.814065639 +0800
@@ -5700,8 +5700,6 @@ CONFIG_CRYPTO_HASH_INFO=y
 CONFIG_CRYPTO_AES_NI_INTEL=y
 CONFIG_CRYPTO_SERPENT_SSE2_586=y
 CONFIG_CRYPTO_TWOFISH_586=y
-CONFIG_CRYPTO_CRC32C_INTEL=y
-# CONFIG_CRYPTO_CRC32_PCLMUL is not set
 # end of Accelerated Cryptographic Algorithms for CPU (x86)

 CONFIG_CRYPTO_HW=y
@@ -5798,7 +5796,10 @@ CONFIG_CRC_T10DIF=y
 CONFIG_CRC64_ROCKSOFT=y
 CONFIG_CRC_ITU_T=y
 CONFIG_CRC32=y
+CONFIG_ARCH_HAS_CRC32=y
 # CONFIG_CRC32_SELFTEST is not set
+# CONFIG_CRC32_IMPL_ARCH_PLUS_SLICEBY8 is not set
+# CONFIG_CRC32_IMPL_ARCH_PLUS_SLICEBY1 is not set
 # CONFIG_CRC32_IMPL_SLICEBY8 is not set
 CONFIG_CRC32_IMPL_SLICEBY4=y
 # CONFIG_CRC32_IMPL_SLICEBY1 is not set


so we still make out below report FYI.


kernel test robot noticed "INFO:task_blocked_for_more_than#seconds" on:

commit: 55d1ecceb8d6a5c9ceff7c6528075bd0ce4e8366 ("x86/crc32: expose CRC32 functions through lib")
https://git.kernel.org/cgit/linux/kernel/git/next/linux-next.git master

[test failed on linux-next/master 8155b4ef3466f0e289e8fcc9e6e62f3f4dceeac2]

in testcase: trinity
version: 
with following parameters:

	runtime: 300s
	group: group-00
	nr_groups: 5



config: i386-randconfig-011-20241220
compiler: gcc-12
test machine: qemu-system-i386 -enable-kvm -cpu SandyBridge -smp 2 -m 4G

(please refer to attached dmesg/kmsg for entire log/backtrace)



If you fix the issue in a separate patch/commit (i.e. not just a new version of
the same patch/commit), kindly add following tags
| Reported-by: kernel test robot <oliver.sang@intel.com>
| Closes: https://lore.kernel.org/oe-lkp/202412251418.8e78094d-lkp@intel.com


[  996.907142][   T25] INFO: task swapper:1 blocked for more than 491 seconds.
[  996.911246][   T25]       Not tainted 6.13.0-rc1-00013-g55d1ecceb8d6 #1
[  996.940897][   T25] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  996.960487][   T25] task:swapper         state:D stack:0     pid:1     tgid:1     ppid:0      flags:0x00004000
[  997.029537][   T25] Call Trace:
[ 997.046615][ T25] __schedule (kernel/sched/core.c:5372 kernel/sched/core.c:6756)
[ 997.060058][ T25] ? async_synchronize_cookie_domain (kernel/async.c:317 (discriminator 9))
[ 997.070156][ T25] schedule (arch/x86/include/asm/preempt.h:26 kernel/sched/core.c:5865 kernel/sched/core.c:5885 kernel/sched/core.c:6834 kernel/sched/core.c:6848)
[ 997.079936][ T25] async_synchronize_cookie_domain (kernel/async.c:317 (discriminator 9))
[ 997.090893][ T25] ? do_wait_intr_irq (kernel/sched/wait.c:383)
[ 997.109522][ T25] wait_for_initramfs (init/initramfs.c:767)
[ 997.118460][ T25] populate_rootfs (init/initramfs.c:778)
[ 997.126493][ T25] do_one_initcall (init/main.c:1266)
[ 997.136390][ T25] ? do_header (init/initramfs.c:771)
[ 997.147147][ T25] ? rdinit_setup (init/main.c:1312)
[ 997.160654][ T25] ? rcu_is_watching (kernel/rcu/tree.c:738)
[ 997.178488][ T25] do_initcalls (init/main.c:1327 init/main.c:1344)
[ 997.195442][ T25] kernel_init_freeable (init/main.c:1579)
[ 997.199167][ T25] ? rest_init (init/main.c:1458)
[ 997.208072][ T25] kernel_init (init/main.c:1468)
[ 997.218814][ T25] ret_from_fork (arch/x86/kernel/process.c:153)
[ 997.225966][ T25] ? rest_init (init/main.c:1458)
[ 997.231504][ T25] ret_from_fork_asm (arch/x86/entry/entry_32.S:737)
[ 997.240527][ T25] entry_INT80_32 (arch/x86/entry/entry_32.S:942)
[  997.318483][   T25]
[  997.318483][   T25] Showing all locks held in the system:
[  997.346428][   T25] 1 lock held by kworker/0:0/6:
[  997.350161][   T25] 4 locks held by kworker/u4:0/9:
[  997.396160][   T25] 1 lock held by khungtaskd/25:
[ 997.399871][ T25] #0: 83f91ab0 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks (include/linux/rcupdate.h:337 include/linux/rcupdate.h:849 kernel/locking/lockdep.c:6744)
[  997.478673][   T25]
[  997.501376][   T25] =============================================
[  997.501376][   T25]
[ 1161.445030][    C0] workqueue: psi_avgs_work hogged CPU for >10000us 11 times, consider switching to WQ_UNBOUND
BUG: kernel hang in boot stage



The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20241225/202412251418.8e78094d-lkp@intel.com



-- 
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki