2024-08-11 21:01:33 depmod 2024-08-11 21:02:15 sleep 300 2024-08-11 21:07:15 rmmod rcutorture [ 120.539382] busted_srcud-torture:--- Start of test: nreaders=1 nfakewriters=4 stat_interval=60 verbose=1 test_no_idle_hz=1 shuffle_interval=3 stutter=5 irqreader=1 fqs_duration=0 fqs_holdoff=0 fqs_stutter=3 test_boost=1/0 test_boost_interval=7 test_boost_duration=4 shutdown_secs=0 stall_cpu=0 stall_cpu_holdoff=10 stall_cpu_irqsoff=0 stall_cpu_block=0 stall_cpu_repeat=0 n_barrier_cbs=0 onoff_interval=0 onoff_holdoff=0 read_exit_delay=13 read_exit_burst=16 nocbs_nthreads=0 nocbs_toggle=1000 test_nmis=0 [ 120.572776] busted_srcud-torture: Creating rcu_torture_writer task [ 120.574122] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 120.574855] busted_srcud-torture: rcu_torture_writer task started [ 120.575774] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 120.576572] busted_srcud-torture: rcu_torture_fakewriter task started [ 120.577479] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 120.578247] busted_srcud-torture: rcu_torture_fakewriter task started [ 120.579110] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 120.583597] busted_srcud-torture: rcu_torture_fakewriter task started [ 120.621307] busted_srcud-torture: rcu_torture_fakewriter task started [ 120.622692] busted_srcud-torture: Creating rcu_torture_reader task [ 120.625775] busted_srcud-torture: rcu_torture_reader task started [ 120.681049] busted_srcud-torture: Creating rcu_torture_stats task [ 120.709919] busted_srcud-torture: Creating torture_shuffle task [ 120.712486] busted_srcud-torture: rcu_torture_stats task started [ 120.723871] busted_srcud-torture: Creating torture_stutter task [ 120.725042] busted_srcud-torture: torture_shuffle task started [ 120.739595] busted_srcud-torture: rcu_torture_fwd_prog_init: Limiting fwd_progress to # CPUs. [ 120.740940] busted_srcud-torture: torture_stutter task started [ 120.741716] busted_srcud-torture: rcu_torture_fwd_prog_init: Disabled, unsupported by RCU flavor under test [ 120.742891] busted_srcud-torture: Creating rcu_torture_read_exit task [ 120.773726] busted_srcud-torture: rcu_torture_read_exit: Start of test [ 120.774510] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 125.752845] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 139.299432] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 139.306414] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 152.819538] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 155.783843] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 169.299390] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 169.304996] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 182.819442] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 185.460154] busted_srcud-torture: rtc: ef5c4558 ver: 1945 tfle: 0 rta: 1945 rtaf: 0 rtf: 1936 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 997 barrier: 0/0:0 read-exits: 64 nocb-toggles: 0:0 [ 185.462078] busted_srcud-torture: !!! [ 185.570110] busted_srcud-torture: Reader Batch: 1461360 0 0 0 0 0 0 0 0 0 0 [ 185.571037] busted_srcud-torture: Free-Block Circulation: 1944 1944 1943 1942 1941 1940 1939 1938 1937 1936 0 [ 185.572168] busted_srcud-torture: Tiny SRCU per-CPU(idx=1): (0,0) gp: 15926->15926 [ 185.803600] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 199.299403] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 199.317002] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 213.060063] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 215.832869] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 229.379543] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 229.382420] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 242.899403] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 245.954440] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 246.900226] busted_srcud-torture: rtc: ef5c48bc ver: 4381 tfle: 0 rta: 4381 rtaf: 0 rtf: 4372 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 2220 barrier: 0/0:0 read-exits: 144 nocb-toggles: 0:0 [ 246.903400] busted_srcud-torture: !!! Reader Pipe: 3428305 2190 332 10 0 0 0 0 0 0 0 [ 246.905078] busted_srcud-torture: Reader Batch: 3430837 0 0 0 0 0 0 0 0 0 0 [ 246.906409] busted_srcud-torture: Free-Block Circulation: 4380 4380 4379 4378 4377 4376 4375 4374 4373 4372 0 [ 246.908234] busted_srcud-torture: Tiny SRCU per-CPU(idx=0): (0,0) gp: 35584->35584 [ 259.459372] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 259.462567] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 272.979403] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 276.071406] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 289.619467] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 289.625133] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 303.139437] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 306.133437] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 308.340317] busted_srcud-torture: rtc: ef5c4964 ver: 6900 tfle: 0 rta: 6900 rtaf: 0 rtf: 6891 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 3498 barrier: 0/0:0 read-exits: 208 nocb-toggles: 0:0 [ 308.344212] busted_srcud-torture: !!! Reader Pipe: 5491097 3403 507 16 0 0 0 0 0 0 0 [ 308.346034] busted_srcud-torture: Reader Batch: 5495023 0 0 0 0 0 0 0 0 0 0 [ 308.346956] busted_srcud-torture: Free-Block Circulation: 6900 6900 6899 6898 6897 6896 6895 6894 6893 6892 0 [ 308.348194] busted_srcud-torture: Tiny SRCU per-CPU(idx=0): (0,0) gp: 56160->56160 [ 320.019434] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 320.025553] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 333.539479] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 336.163283] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 349.859540] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 349.864917] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 363.539436] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 366.193895] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 369.779498] busted_srcud-torture: rtc: ef5c4948 ver: 9449 tfle: 0 rta: 9449 rtaf: 0 rtf: 9440 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 4788 barrier: 0/0:0 read-exits: 272 nocb-toggles: 0:0 [ 369.781588] busted_srcud-torture: !!! Reader Pipe: 7439947 4662 681 25 0 0 0 0 0 0 0 [ 369.782558] busted_srcud-torture: Reader Batch: 7445315 0 0 0 0 0 0 0 0 0 0 [ 369.783364] busted_srcud-torture: Free-Block Circulation: 9448 9448 9447 9446 9445 9444 9443 9442 9441 9440 0 [ 369.784555] busted_srcud-torture: Tiny SRCU per-CPU(idx=1): (0,2) gp: 76894->76894 [ 379.699455] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 379.705534] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 393.459525] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 396.233879] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 409.779519] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 409.783213] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 420.872548] busted_srcud-torture: Stopping torture_shuffle task [ 420.873225] busted_srcud-torture: torture_shuffle is stopping [ 420.873899] busted_srcud-torture: Stopping torture_stutter task [ 420.874522] busted_srcud-torture: torture_stutter is stopping [ 420.879130] busted_srcud-torture: rcu_torture_reader is stopping [ 420.882492] busted_srcud-torture: rcu_torture_writer is stopping [ 420.899646] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 420.919656] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 420.939604] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 420.940803] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 421.219841] busted_srcud-torture: Stopping rcutorture_read_exit task [ 421.221105] busted_srcud-torture: rcu_torture_read_exit is stopping [ 421.222288] busted_srcud-torture: Stopping rcu_torture_writer task [ 421.223603] busted_srcud-torture: Stopping rcu_torture_reader task [ 421.224901] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 421.226208] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 421.227541] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 421.228833] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 421.248517] busted_srcud-torture: Stopping rcu_torture_stats task [ 421.254176] busted_srcud-torture: rtc: 00000000 VER: 11627 tfle: 0 rta: 11627 rtaf: 0 rtf: 11617 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 5893 barrier: 0/0:0 read-exits: 320 nocb-toggles: 0:0 [ 421.256846] busted_srcud-torture: !!! Reader Pipe: 9072964 5713 811 29 0 0 0 0 0 0 0 [ 421.257981] busted_srcud-torture: Reader Batch: 9079517 0 0 0 0 0 0 0 0 0 0 [ 421.258885] busted_srcud-torture: Free-Block Circulation: 11626 11625 11624 11623 11622 11621 11620 11619 11618 11617 0 [ 421.261728] busted_srcud-torture: Tiny SRCU per-CPU(idx=1): (0,0) gp: 94654->94654 [ 421.262681] busted_srcud-torture: rcu_torture_stats is stopping [ 421.439075] busted_srcud-torture: rtc: 00000000 VER: 11627 tfle: 0 rta: 11627 rtaf: 0 rtf: 11617 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 5893 barrier: 0/0:0 read-exits: 320 nocb-toggles: 0:0 [ 421.447017] busted_srcud-torture: !!! Reader Pipe: 9072964 5713 811 29 0 0 0 0 0 0 0 [ 421.448592] busted_srcud-torture: Reader Batch: 9079517 0 0 0 0 0 0 0 0 0 0 [ 421.453612] busted_srcud-torture: Free-Block Circulation: 11626 11625 11624 11623 11622 11621 11620 11619 11618 11617 0 [ 421.456651] busted_srcud-torture: Tiny SRCU per-CPU(idx=0): (0,0) gp: 0->0 [ 421.468954] busted_srcud-torture:--- End of test: FAILURE: nreaders=1 nfakewriters=4 stat_interval=60 verbose=1 test_no_idle_hz=1 shuffle_interval=3 stutter=5 irqreader=1 fqs_duration=0 fqs_holdoff=0 fqs_stutter=3 test_boost=1/0 test_boost_interval=7 test_boost_duration=4 shutdown_secs=0 stall_cpu=0 stall_cpu_holdoff=10 stall_cpu_irqsoff=0 stall_cpu_block=0 stall_cpu_repeat=0 n_barrier_cbs=0 onoff_interval=0 onoff_holdoff=0 read_exit_delay=13 read_exit_burst=16 nocbs_nthreads=0 nocbs_toggle=1000 test_nmis=0