2023-06-06 04:42:15 depmod 2023-06-06 04:44:39 sleep 300 2023-06-06 04:49:39 rmmod rcutorture [ 267.972445] 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 n_barrier_cbs=0 onoff_interval=3 onoff_holdoff=30 read_exit_delay=13 read_exit_burst=16 nocbs_nthreads=0 nocbs_toggle=1000 test_nmis=0 [ 267.978459] busted_srcud-torture: Creating rcu_torture_writer task [ 268.027807] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 268.028656] busted_srcud-torture: rcu_torture_writer task started [ 268.046749] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 268.051385] busted_srcud-torture: rcu_torture_fakewriter task started [ 268.061732] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 268.068264] busted_srcud-torture: rcu_torture_fakewriter task started [ 268.079398] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 268.082945] busted_srcud-torture: rcu_torture_fakewriter task started [ 268.097236] busted_srcud-torture: Creating rcu_torture_reader task [ 268.100725] busted_srcud-torture: rcu_torture_fakewriter task started [ 268.119612] busted_srcud-torture: Creating rcu_torture_stats task [ 268.123325] busted_srcud-torture: rcu_torture_reader task started [ 268.153019] busted_srcud-torture: Creating torture_shuffle task [ 268.154770] busted_srcud-torture: rcu_torture_stats task started [ 268.172555] busted_srcud-torture: Creating torture_stutter task [ 268.174927] busted_srcud-torture: torture_shuffle task started [ 268.182597] busted_srcud-torture: Creating torture_onoff task [ 268.184874] busted_srcud-torture: torture_stutter task started [ 268.200920] busted_srcud-torture: rcu_torture_fwd_prog_init: Disabled, unsupported by RCU flavor under test [ 268.201865] busted_srcud-torture: Creating rcu_torture_read_exit task [ 268.203617] busted_srcud-torture: torture_onoff task started [ 268.237549] busted_srcud-torture: rcu_torture_read_exit: Start of test [ 268.238273] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 268.678307] busted_srcud-torture: torture_onoff begin holdoff [ 273.583776] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 287.200121] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 287.217579] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 298.966122] busted_srcud-torture: torture_onoff end holdoff [ 300.532429] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 303.799185] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 317.272030] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 317.415153] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 328.156163] busted_srcud-torture: rtc: 00000000df4d836e ver: 6700 tfle: 0 rta: 6700 rtaf: 0 rtf: 6690 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 4795 onoff: 219/220:220/220 21,348:24,226 15083:10599 (HZ=1000) barrier: 0/0:0 read-exits: 64 nocb-toggles: 0:0 [ 328.158589] busted_srcud-torture: !!! [ 328.184132] busted_srcud-torture: Reader Batch: 570022 553 63 27 5 1 1 0 0 0 0 [ 328.186246] busted_srcud-torture: Free-Block Circulation: 6699 6699 6697 6696 6695 6694 6693 6692 6691 6690 0 [ 328.187319] rcu: busted_srcud-torture: Tree SRCU g68773 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=1): 0(0,0 C) 1(0,0 .) T(0,0) [ 330.606162] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 333.536850] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 346.720577] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 347.085952] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 360.390740] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 363.468452] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 376.654807] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 376.681017] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 388.207459] busted_srcud-torture: rtc: 00000000135521de ver: 12052 tfle: 0 rta: 12053 rtaf: 0 rtf: 12039 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 6794 onoff: 680/681:681/681 21,379:18,296 45068:33402 (HZ=1000) barrier: 0/0:0 read-exits: 128 nocb-toggles: 0:0 [ 388.209883] busted_srcud-torture: !!! Reader Pipe: 737805 211 8 1 0 0 0 0 0 0 0 [ 388.210805] busted_srcud-torture: Reader Batch: 737175 712 91 33 11 2 1 0 0 0 0 [ 388.211641] busted_srcud-torture: Free-Block Circulation: 12051 12051 12050 12048 12046 12044 12042 12041 12040 12039 0 [ 388.212872] rcu: busted_srcud-torture: Tree SRCU g128657 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=0): 0(0,0 C) 1(0,0 .) T(0,0) [ 389.863198] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 393.648446] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 406.846160] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 406.854753] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 420.062482] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 423.814299] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 436.924725] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 437.237982] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 448.220486] busted_srcud-torture: rtc: 00000000eeabb4b8 ver: 17540 tfle: 0 rta: 17540 rtaf: 0 rtf: 17530 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 8672 onoff: 1143/1144:1144/1144 21,379:18,296 74633:56373 (HZ=1000) barrier: 0/0:0 read-exits: 192 nocb-toggles: 0:0 [ 448.222790] busted_srcud-torture: !!! Reader Pipe: 891529 231 12 1 0 0 0 0 0 0 0 [ 448.223638] busted_srcud-torture: Reader Batch: 890775 827 115 37 16 2 1 0 0 0 0 [ 448.224468] busted_srcud-torture: Free-Block Circulation: 17539 17538 17537 17536 17535 17534 17533 17532 17531 17530 0 [ 448.225534] rcu: busted_srcud-torture: Tree SRCU g190405 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=1): 0(0,0 C) 1(0,0 .) T(0,0) [ 450.399906] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 453.561256] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 466.886832] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 467.588902] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 480.801212] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 484.069705] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 497.182169] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 497.282042] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 508.234112] busted_srcud-torture: rtc: 00000000f9fef3a8 ver: 23446 tfle: 0 rta: 23446 rtaf: 0 rtf: 23433 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 10920 onoff: 1621/1622:1622/1622 21,530:18,323 104516:78945 (HZ=1000) barrier: 0/0:0 read-exits: 256 nocb-toggles: 0:0 [ 508.236538] busted_srcud-torture: !!! Reader Pipe: 1080589 269 14 1 0 0 0 0 0 0 0 [ 508.237471] busted_srcud-torture: Reader Batch: 1079685 985 134 43 21 3 2 0 0 0 0 [ 508.238360] busted_srcud-torture: Free-Block Circulation: 23445 23444 23442 23441 23439 23438 23436 23435 23434 23433 0 [ 508.239579] rcu: busted_srcud-torture: Tree SRCU g255873 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=0): 0(0,0 C) 1(0,0 .) T(0,0) [ 510.422147] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 513.666857] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 526.950198] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 527.390200] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 540.486169] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 544.049864] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 557.655396] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 557.794582] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 568.238151] busted_srcud-torture: rtc: 00000000df4d836e ver: 28830 tfle: 0 rta: 28830 rtaf: 0 rtf: 28817 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 12874 onoff: 2066/2066:2066/2067 21,530:18,343 134923:101406 (HZ=1000) barrier: 0/0:0 read-exits: 320 nocb-toggles: 0:0 [ 568.240725] busted_srcud-torture: !!! Reader Pipe: 1254191 305 19 1 0 0 0 0 0 0 0 [ 568.241662] busted_srcud-torture: Reader Batch: 1253173 1113 149 49 26 4 2 0 0 0 0 [ 568.242563] busted_srcud-torture: Free-Block Circulation: 28829 28828 28826 28825 28824 28822 28821 28819 28818 28817 0 [ 568.243728] rcu: busted_srcud-torture: Tree SRCU g317477 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=1): 0(0,0 C) 1(0,0 .) T(0,0) [ 569.644938] busted_srcud-torture: Stopping torture_shuffle task [ 569.657164] busted_srcud-torture: torture_shuffle is stopping [ 569.661835] busted_srcud-torture: torture_onoff is stopping [ 569.665945] busted_srcud-torture: Stopping torture_stutter task [ 569.669177] busted_srcud-torture: torture_stutter is stopping [ 569.670334] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 569.671634] busted_srcud-torture: rcu_torture_writer is stopping [ 569.672411] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 569.673586] busted_srcud-torture: rcu_torture_reader is stopping [ 569.676630] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 569.677361] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 569.679717] busted_srcud-torture: Stopping torture_onoff task [ 570.070264] busted_srcud-torture: rcu_torture_read_exit is stopping [ 570.070299] busted_srcud-torture: Stopping rcutorture_read_exit task [ 570.073731] busted_srcud-torture: Stopping rcu_torture_writer task [ 570.074503] busted_srcud-torture: Stopping rcu_torture_reader task [ 570.077443] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 570.079870] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 570.080759] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 570.083491] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 570.085911] busted_srcud-torture: Stopping rcu_torture_stats task [ 570.087742] busted_srcud-torture: rtc: 0000000000000000 VER: 28922 tfle: 0 rta: 28922 rtaf: 0 rtf: 28913 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 12924 onoff: 2076/2076:2077/2077 21,530:18,343 135617:102016 (HZ=1000) barrier: 0/0:0 read-exits: 320 nocb-toggles: 0:0 [ 570.092099] busted_srcud-torture: !!! Reader Pipe: 1258049 305 19 1 0 0 0 0 0 0 0 [ 570.092990] busted_srcud-torture: Reader Batch: 1257026 1118 149 49 26 4 2 0 0 0 0 [ 570.093866] busted_srcud-torture: Free-Block Circulation: 28921 28921 28920 28919 28918 28917 28916 28915 28914 28913 0 [ 570.095033] rcu: busted_srcud-torture: Tree SRCU g318512 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=0): 0(0,0 .) 1(0,0 .) T(0,0) [ 570.104033] busted_srcud-torture: rcu_torture_stats is stopping [ 570.317164] busted_srcud-torture: rtc: 0000000000000000 VER: 28922 tfle: 0 rta: 28922 rtaf: 0 rtf: 28913 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 12924 onoff: 2076/2076:2077/2077 21,530:18,343 135617:102016 (HZ=1000) barrier: 0/0:0 read-exits: 320 nocb-toggles: 0:0 [ 570.319635] busted_srcud-torture: !!! Reader Pipe: 1258049 305 19 1 0 0 0 0 0 0 0 [ 570.320622] busted_srcud-torture: Reader Batch: 1257026 1118 149 49 26 4 2 0 0 0 0 [ 570.321542] busted_srcud-torture: Free-Block Circulation: 28921 28921 28920 28919 28918 28917 28916 28915 28914 28913 0 [ 570.322756] rcu: busted_srcud-torture: Tree SRCU g0 state 0 (SRCU_SIZE_SMALL) per-CPU(idx=0): 0(0,0 .) 1(0,0 .) T(0,0) [ 570.327319] 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 n_barrier_cbs=0 onoff_interval=3 onoff_holdoff=30 read_exit_delay=13 read_exit_burst=16 nocbs_nthreads=0 nocbs_toggle=1000 test_nmis=0