-
Bug
-
Resolution: Unresolved
-
Undefined
-
None
-
False
-
-
False
-
None
-
rhel-kernel-ft
-
-
The beaker job: https://beaker.engineering.redhat.com/jobs/11697368
And was introduce by https://gitlab.cee.redhat.com/kernel-qe/kernel/-/commit/caa6f65fae8bce3935ed4dfcbda02e251c5e91a3
:::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: lock_busted :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: [ 05:28:37 ] :: [ PASS ] :: Command 'insmod /root/rpmbuild/BUILD/kernel-4.18.0-372.162.1.el8_6/linux-4.18.0-372.162.1.el8.s390x/kernel/locking/locktorture.ko torture_type=lock_busted' (Expected 0, got 0) :: [ 05:38:37 ] :: [ PASS ] :: Command 'rmmod locktorture' (Expected 0, got 0) :: [ 05:38:37 ] :: [ FAIL ] :: Command 'dmesg | grep -E lock_busted-t.*SUCCESS' (Expected 0, got 1) :::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::::: :: Duration: 600s :: Assertions: 2 good, 1 bad :: RESULT: FAIL (lock_busted)
[ 23.588938] restraintd[5377]: ** Completed Task : 202678413 [ 23.945765] restraintd[5377]: ** Fetching task: 202678414 [/mnt/tests/repo.usersys.redhat.com/kernel-tests/general/locking/torture] [ 24.392317] restraintd[5377]: ** Preparing metadata [ 24.615167] restraintd[5377]: ** Refreshing peer role hostnames: Retries 0 [ 24.856111] restraintd[5377]: ** Updating env vars [ 24.856334] restraintd[5377]: ** Updating external watchdog: 88200 seconds [ 25.233495] restraintd[5377]: ** Installing dependencies [ 76.096206] restraintd[5377]: ** Running task: 202678414 [git://repo.usersys.redhat.com/kernel-tests?master#general/locking/torture] [-- MARK -- Wed Sep 24 02:05:00 2025] [ 136.020162] restraintd[5377]: *** Current Time: Tue Sep 23 22:05:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 175.151760] torture: no symbol version for module_layout [ 175.151820] torture: module verification failed: signature and/or required key missing - tainting kernel [ 185.508272] lock_busted-torture:--- Start of test: nwriters_stress=8 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 185.508296] lock_busted-torture: Creating torture_shuffle task [ 185.508823] lock_busted-torture: Creating torture_stutter task [ 185.508826] lock_busted-torture: torture_shuffle task started [ 185.508921] lock_busted-torture: Creating lock_torture_writer task [ 185.508931] lock_busted-torture: torture_stutter task started [ 185.508978] lock_busted-torture: Creating lock_torture_writer task [ 185.508982] lock_busted-torture: lock_torture_writer task started [ 185.509018] lock_busted-torture: Creating lock_torture_writer task [ 185.509025] lock_busted-torture: lock_torture_writer task started [ 185.509069] lock_busted-torture: Creating lock_torture_writer task [ 185.509073] lock_busted-torture: lock_torture_writer task started [ 185.509095] lock_busted-torture: Creating lock_torture_writer task [ 185.509108] lock_busted-torture: lock_torture_writer task started [ 185.509147] lock_busted-torture: Creating lock_torture_writer task [ 185.509150] lock_busted-torture: lock_torture_writer task started [ 185.509361] lock_busted-torture: Creating lock_torture_writer task [ 185.509373] lock_busted-torture: lock_torture_writer task started [ 185.514446] lock_busted-torture: Creating lock_torture_writer task [ 185.514453] lock_busted-torture: lock_torture_writer task started [ 185.514475] lock_busted-torture: Creating lock_torture_stats task [ 185.514479] lock_busted-torture: lock_torture_writer task started [ 185.514668] lock_busted-torture: lock_torture_stats task started [ 185.555568] WARNING: CPU: 2 PID: 28646 at kernel/locking/locktorture.c:682 lock_torture_writer+0x17e/0x1b0 [locktorture] [ 185.555582] Modules linked in: locktorture(E) torture(E) lcs ctcm fsm qeth ccwgroup zfcp qdio scsi_transport_fc dasd_fba_mod dasd_eckd_mod dasd_mod sunrpc ghash_s390 prng xts virtio_input aes_s390 des_s390 des_generic sha3_512_s390 sha3_256_s390 vfio_ccw vfio_mdev mdev vfio_iommu_type1 sha512_s390 vfio zcrypt_cex4 xfs libcrc32c virtio_gpu drm_kms_helper cfbfillrect syscopyarea cfbimgblt sysfillrect sysimgblt fb_sys_fops cfbcopyarea drm fb font i2c_core drm_panel_orientation_quirks virtio_blk virtio_net net_failover failover dm_mirror dm_region_hash dm_log dm_mod pkey zcrypt [ 185.555625] CPU: 2 PID: 28646 Comm: lock_torture_wr Kdump: loaded Tainted: G E --------- - - 4.18.0-372.150.1.el8_6.s390x #1 [ 185.555629] Hardware name: IBM 3931 LA1 400 (KVM/Linux) [ 185.555630] Krnl PSW : 0704e00180000000 000003ff80246c0a (lock_torture_writer+0x182/0x1b0 [locktorture]) [ 185.555635] R:0 T:1 IO:1 EX:1 Key:0 M:1 W:0 P:0 AS:3 CC:2 PM:0 RI:0 EA:3 [ 185.555638] Krnl GPRS: 000000000000018a 000003ff802463f8 0000000000000000 00000001f3f82000 [ 185.555639] 000000000000daf0 000000012561cda8 000003ff80249c60 00000001254bac00 [ 185.555641] 0000000000000006 0000000125766e60 00000000ed91be90 000003ff80249c62 [ 185.555643] 0000000083665500 0000000000000000 000003ff80246b32 00000000ed91bdf0 [ 185.555652] Krnl Code: 000003ff80246bfc: eb019000007a agsi 0(%r9),1 [ 185.555652] 000003ff80246c02: a7f4ffa4 brc 15,3ff80246b4a [ 185.555652] #000003ff80246c06: af000000 mc 0,0 [ 185.555652] >000003ff80246c0a: eb019000007a agsi 0(%r9),1 [ 185.555652] 000003ff80246c10: a7f4ff97 brc 15,3ff80246b3e [ 185.555652] 000003ff80246c14: c0e5fffc00da brasl %r14,3ff801c6dc8 [ 185.555652] 000003ff80246c1a: c04000000ad8 larl %r4,3ff802481ca [ 185.555652] 000003ff80246c20: c43800001428 lgrl %r3,3ff80249470 [ 185.555666] Call Trace: [ 185.555667] ([<000003ff80246bae>] lock_torture_writer+0x126/0x1b0 [locktorture]) [ 185.555670] [<00000001247badb8>] kthread+0x148/0x170 [ 185.555676] [<0000000124f58256>] kernel_thread_starter+0x6/0x10 [ 185.555761] [<0000000124f58250>] kernel_thread_starter+0x0/0x10 [ 185.555764] Last Breaking-Event-Address: [ 185.555765] [<000003ff80246b3a>] lock_torture_writer+0xb2/0x1b0 [locktorture] [ 185.555768] ---[ end trace 9cc970325ece2c64 ]--- [ 196.047776] restraintd[5377]: *** Current Time: Tue Sep 23 22:06:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 200.494750] hrtimer: interrupt took 2336 ns [ 249.214762] Writes: Total: 1024082314 Max/Min: 145792267/110703405 Fail: 1 !!! [ 256.055038] restraintd[5377]: *** Current Time: Tue Sep 23 22:07:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 310.654727] Writes: Total: 2025628491 Max/Min: 289504278/226695003 Fail: 1 !!! [ 316.023604] restraintd[5377]: *** Current Time: Tue Sep 23 22:08:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 372.123067] Writes: Total: 3031349058 Max/Min: 429385951/356563219 Fail: 1 !!! [ 376.016893] restraintd[5377]: *** Current Time: Tue Sep 23 22:09:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [-- MARK -- Wed Sep 24 02:10:00 2025] [ 433.544715] Writes: Total: 4020988816 Max/Min: 563665871/472321975 Fail: 1 !!! [ 436.007662] restraintd[5377]: *** Current Time: Tue Sep 23 22:10:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 494.998083] Writes: Total: 4963911902 Max/Min: 693765066/590736877 Fail: 1 !!! [ 496.007353] restraintd[5377]: *** Current Time: Tue Sep 23 22:11:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 556.007854] restraintd[5377]: *** Current Time: Tue Sep 23 22:12:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 556.474768] Writes: Total: 5878930503 Max/Min: 812379632/696814356 Fail: 1 !!! [ 616.040353] restraintd[5377]: *** Current Time: Tue Sep 23 22:13:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 617.854719] Writes: Total: 6817269115 Max/Min: 935338085/813863608 Fail: 1 !!! [ 676.006717] restraintd[5377]: *** Current Time: Tue Sep 23 22:14:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 679.294664] Writes: Total: 7709598485 Max/Min: 1061177733/924219448 Fail: 1 !!! [-- MARK -- Wed Sep 24 02:15:00 2025] [ 736.116817] restraintd[5377]: *** Current Time: Tue Sep 23 22:15:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 740.743061] Writes: Total: 8659939238 Max/Min: 1186132974/1045863474 Fail: 1 !!! [ 785.537160] lock_busted-torture: Stopping torture_shuffle task [ 785.537176] lock_busted-torture: Stopping torture_shuffle [ 785.537200] lock_busted-torture: Stopping torture_stutter task [ 785.557553] lock_busted-torture: Stopping lock_torture_writer [ 785.557554] lock_busted-torture: Stopping torture_stutter [ 785.557554] lock_busted-torture: Stopping lock_torture_writer [ 785.557559] lock_busted-torture: Stopping lock_torture_writer [ 785.557560] lock_busted-torture: Stopping lock_torture_writer [ 785.557563] lock_busted-torture: Stopping lock_torture_writer [ 785.557582] lock_busted-torture: Stopping lock_torture_writer task [ 785.557609] lock_busted-torture: Stopping lock_torture_writer [ 785.557613] lock_busted-torture: Stopping lock_torture_writer [ 785.557615] lock_busted-torture: Stopping lock_torture_writer [ 785.557625] lock_busted-torture: Stopping lock_torture_writer task [ 785.557641] lock_busted-torture: Stopping lock_torture_writer task [ 785.557657] lock_busted-torture: Stopping lock_torture_writer task [ 785.557670] lock_busted-torture: Stopping lock_torture_writer task [ 785.557684] lock_busted-torture: Stopping lock_torture_writer task [ 785.557700] lock_busted-torture: Stopping lock_torture_writer task [ 785.557716] lock_busted-torture: Stopping lock_torture_writer task [ 785.557735] lock_busted-torture: Stopping lock_torture_stats task [ 785.557746] Writes: Total: 9330373500 Max/Min: 1274709037/1127735143 Fail: 1 !!! [ 785.557750] lock_busted-torture: Stopping lock_torture_stats [ 785.557763] Writes: Total: 9330373500 Max/Min: 1274709037/1127735143 Fail: 1 !!! [ 785.557765] lock_busted-torture:--- End of test: FAILURE: nwriters_stress=8 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 786.341634] mutex_lock-torture:--- Start of test: nwriters_stress=8 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0 [ 786.341648] mutex_lock-torture: Creating torture_shuffle task [ 786.341697] mutex_lock-torture: Creating torture_stutter task [ 786.341704] mutex_lock-torture: torture_shuffle task started [ 786.341730] mutex_lock-torture: Creating lock_torture_writer task [ 786.341737] mutex_lock-torture: torture_stutter task started [ 786.341760] mutex_lock-torture: Creating lock_torture_writer task [ 786.341763] mutex_lock-torture: lock_torture_writer task started [ 786.341785] mutex_lock-torture: Creating lock_torture_writer task [ 786.341787] mutex_lock-torture: lock_torture_writer task started [ 786.341797] mutex_lock-torture: Creating lock_torture_writer task [ 786.341806] mutex_lock-torture: lock_torture_writer task started [ 786.341811] mutex_lock-torture: Creating lock_torture_writer task [ 786.341819] mutex_lock-torture: lock_torture_writer task started [ 786.341823] mutex_lock-torture: Creating lock_torture_writer task [ 786.341832] mutex_lock-torture: lock_torture_writer task started [ 786.345004] mutex_lock-torture: Creating lock_torture_writer task [ 786.347559] mutex_lock-torture: lock_torture_writer task started [ 786.349021] mutex_lock-torture: Creating lock_torture_writer task [ 786.349023] mutex_lock-torture: lock_torture_writer task started [ 786.349034] mutex_lock-torture: Creating lock_torture_stats task [ 786.349043] mutex_lock-torture: lock_torture_writer task started [ 786.355848] mutex_lock-torture: lock_torture_stats task started [ 796.021448] restraintd[5377]: *** Current Time: Tue Sep 23 22:16:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 848.272359] Writes: Total: 3089 Max/Min: 400/369 Fail: 0 [ 856.009598] restraintd[5377]: *** Current Time: Tue Sep 23 22:17:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 909.704669] Writes: Total: 6151 Max/Min: 791/738 Fail: 0 [ 916.016938] restraintd[5377]: *** Current Time: Tue Sep 23 22:18:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 971.137957] Writes: Total: 9214 Max/Min: 1187/1107 Fail: 0 [ 976.049316] restraintd[5377]: *** Current Time: Tue Sep 23 22:19:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [-- MARK -- Wed Sep 24 02:20:00 2025] [ 1032.581756] Writes: Total: 12277 Max/Min: 1569/1486 Fail: 0 [ 1036.016791] restraintd[5377]: *** Current Time: Tue Sep 23 22:20:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 1094.014568] Writes: Total: 15341 Max/Min: 1945/1859 Fail: 0 [ 1096.007735] restraintd[5377]: *** Current Time: Tue Sep 23 22:21:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 1155.464588] Writes: Total: 18405 Max/Min: 2334/2215 Fail: 0 [ 1156.010889] restraintd[5377]: *** Current Time: Tue Sep 23 22:22:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 1216.035705] restraintd[5377]: *** Current Time: Tue Sep 23 22:23:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 1216.904530] Writes: Total: 21469 Max/Min: 2746/2571 Fail: 0 [ 1276.024973] restraintd[5377]: *** Current Time: Tue Sep 23 22:24:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 1278.334640] Writes: Total: 24532 Max/Min: 3140/2948 Fail: 0 [-- MARK -- Wed Sep 24 02:25:00 2025] [ 1336.052202] restraintd[5377]: *** Current Time: Tue Sep 23 22:25:18 2025 Localwatchdog at: Wed Sep 24 22:04:18 2025 [ 1339.794487] Writes: Total: 27596 Max/Min: 3527/3329 Fail: 0
While from the kernel source code:
108 static int torture_lock_busted_write_lock(int tid __maybe_unused) 109 { 110 return 0; /* BUGGY, do not use in real life!!! */ 111 } 112 113 static void torture_lock_busted_write_delay(struct torture_random_state *trsp) 114 { 115 const unsigned long longdelay_ms = 100; 116 117 /* We want a long delay occasionally to force massive contention. */ 118 if (!(torture_random(trsp) % 119 (cxt.nrealwriters_stress * 2000 * longdelay_ms))) 120 mdelay(longdelay_ms); 121 if (!(torture_random(trsp) % (cxt.nrealwriters_stress * 20000))) 122 torture_preempt_schedule(); /* Allow test to be preempted. */ 123 } 124 125 static void torture_lock_busted_write_unlock(int tid __maybe_unused) 126 { 127 /* BUGGY, do not use in real life!!! */ 128 } 129 130 static void torture_boost_dummy(struct torture_random_state *trsp) 131 { 132 /* Only rtmutexes care about priority */ 133 } 134 135 static struct lock_torture_ops lock_busted_ops = { 136 .writelock = torture_lock_busted_write_lock, 137 .write_delay = torture_lock_busted_write_delay, 138 .task_boost = torture_boost_dummy, 139 .writeunlock = torture_lock_busted_write_unlock, 140 .readlock = NULL, 141 .read_delay = NULL, 142 .readunlock = NULL, 143 .name = "lock_busted" 144 };
The "lock/unlock" ops are empty, so they will do nothing to protect the "readers/writers". So for the "lock_busted" test possibly it should fail or success as expected ?
Such as for https://beaker.engineering.redhat.com/jobs/11697374 the test passed instead.
- links to
