2024-06-23 16:42:09 depmod 2024-06-23 16:42:34 sleep 300 2024-06-23 16:47:34 rmmod rcutorture [ 52.247763] 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=0 onoff_holdoff=0 read_exit_delay=13 read_exit_burst=16 nocbs_nthreads=0 nocbs_toggle=1000 test_nmis=0 [ 52.274146] busted_srcud-torture: Creating rcu_torture_writer task [ 52.287874] busted_srcud-torture: rcu_torture_writer task started [ 52.288688] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 52.290562] busted_srcud-torture: rcu_torture_fakewriter task started [ 52.292939] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 52.304074] busted_srcud-torture: rcu_torture_fakewriter task started [ 52.305589] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 52.307162] busted_srcud-torture: rcu_torture_fakewriter task started [ 52.309782] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 52.320016] busted_srcud-torture: rcu_torture_fakewriter task started [ 52.321254] busted_srcud-torture: Creating rcu_torture_reader task [ 52.322544] busted_srcud-torture: rcu_torture_reader task started [ 52.338037] busted_srcud-torture: Creating rcu_torture_stats task [ 52.377014] busted_srcud-torture: Creating torture_shuffle task [ 52.380178] busted_srcud-torture: rcu_torture_stats task started [ 52.391818] busted_srcud-torture: Creating torture_stutter task [ 52.395807] busted_srcud-torture: torture_shuffle task started [ 52.397339] busted_srcud-torture: rcu_torture_fwd_prog_init: Limiting fwd_progress to # CPUs. [ 52.398407] busted_srcud-torture: rcu_torture_fwd_prog_init: Disabled, unsupported by RCU flavor under test [ 52.399420] busted_srcud-torture: Creating rcu_torture_read_exit task [ 52.401189] busted_srcud-torture: torture_stutter task started [ 52.428911] busted_srcud-torture: rcu_torture_read_exit: Start of test [ 52.429680] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 57.408693] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 70.723675] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 70.799904] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 84.227801] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 87.399300] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 100.707811] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 100.710896] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 114.019811] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 114.211831] busted_srcud-torture: rtc: e5c217e5 ver: 12985 tfle: 0 rta: 12985 rtaf: 0 rtf: 12976 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 1899 barrier: 0/0:0 read-exits: 64 nocb-toggles: 0:0 [ 114.213610] busted_srcud-torture: !!! [ 114.293741] busted_srcud-torture: Reader Batch: 688055 0 0 0 0 0 0 0 0 0 0 [ 114.294491] busted_srcud-torture: Free-Block Circulation: 12984 12984 12983 12982 12981 12980 12979 12978 12977 12976 0 [ 114.300167] busted_srcud-torture: Tiny SRCU per-CPU(idx=1): (0,0) gp: 101186->101186 [ 117.388673] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 130.819672] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 130.822915] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 144.131783] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 147.373152] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 160.675681] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 160.680473] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 173.987794] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 175.651776] busted_srcud-torture: rtc: c0778257 ver: 34277 tfle: 0 rta: 34277 rtaf: 0 rtf: 34268 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 5434 barrier: 0/0:0 read-exits: 128 nocb-toggles: 0:0 [ 175.653578] busted_srcud-torture: !!! Reader Pipe: 2027463 12270 3579 123 2 0 0 0 0 0 0 [ 175.654501] busted_srcud-torture: Reader Batch: 2043437 0 0 0 0 0 0 0 0 0 0 [ 175.655237] busted_srcud-torture: Free-Block Circulation: 34276 34276 34275 34274 34273 34272 34271 34270 34269 34268 0 [ 175.663726] busted_srcud-torture: Tiny SRCU per-CPU(idx=1): (0,0) gp: 264942->264942 [ 177.369834] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 190.819681] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 190.828990] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 204.137026] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 207.351419] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 220.963679] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 220.973416] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 234.340393] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 237.091780] busted_srcud-torture: rtc: ac48637b ver: 54887 tfle: 0 rta: 54887 rtaf: 0 rtf: 54878 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 8887 barrier: 0/0:0 read-exits: 192 nocb-toggles: 0:0 [ 237.093590] busted_srcud-torture: !!! Reader Pipe: 3403093 20227 5902 215 6 0 0 0 0 0 0 [ 237.094443] busted_srcud-torture: Reader Batch: 3429443 0 0 0 0 0 0 0 0 0 0 [ 237.095181] busted_srcud-torture: Free-Block Circulation: 54886 54886 54885 54884 54883 54882 54881 54880 54879 54878 0 [ 237.103502] busted_srcud-torture: Tiny SRCU per-CPU(idx=1): (0,0) gp: 423278->423278 [ 237.410813] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 250.724044] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 250.726997] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 264.035732] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 267.408912] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 280.739745] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 280.745475] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 294.051780] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 297.389918] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 298.531853] busted_srcud-torture: rtc: 4ce2ae7d ver: 75923 tfle: 0 rta: 75923 rtaf: 0 rtf: 75914 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 12405 barrier: 0/0:0 read-exits: 272 nocb-toggles: 0:0 [ 298.541261] busted_srcud-torture: !!! Reader Pipe: 4811690 28570 8306 316 8 0 0 0 0 0 0 [ 298.542186] busted_srcud-torture: Reader Batch: 4848890 0 0 0 0 0 0 0 0 0 0 [ 298.542954] busted_srcud-torture: Free-Block Circulation: 75924 75924 75923 75922 75921 75920 75919 75918 75917 75916 0 [ 298.549209] busted_srcud-torture: Tiny SRCU per-CPU(idx=0): (0,0) gp: 588256->588256 [ 310.691615] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 310.697893] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 324.003799] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 327.384648] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 340.867816] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 340.877112] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 352.482542] busted_srcud-torture: Stopping torture_shuffle task [ 352.483153] busted_srcud-torture: torture_shuffle is stopping [ 352.486829] busted_srcud-torture: Stopping torture_stutter task [ 352.487462] busted_srcud-torture: torture_stutter is stopping [ 353.155966] busted_srcud-torture: rcu_torture_read_exit is stopping [ 353.156671] busted_srcud-torture: Stopping rcutorture_read_exit task [ 353.157384] busted_srcud-torture: Stopping rcu_torture_writer task [ 353.158028] busted_srcud-torture: rcu_torture_writer is stopping [ 353.158668] busted_srcud-torture: Stopping rcu_torture_reader task [ 353.159316] busted_srcud-torture: rcu_torture_reader is stopping [ 353.181313] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 353.182054] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 353.182751] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 353.183460] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 353.204106] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 353.204877] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 353.205551] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 353.206196] busted_srcud-torture: rcu_torture_fakewriter is stopping [ 353.207516] busted_srcud-torture: Stopping rcu_torture_stats task [ 353.219853] busted_srcud-torture: rtc: 00000000 VER: 96139 tfle: 0 rta: 96139 rtaf: 0 rtf: 96130 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 15738 barrier: 0/0:0 read-exits: 320 nocb-toggles: 0:0 [ 353.221698] busted_srcud-torture: !!! Reader Pipe: 6151759 36356 10612 405 11 0 0 0 0 0 0 [ 353.222568] busted_srcud-torture: Reader Batch: 6199143 0 0 0 0 0 0 0 0 0 0 [ 353.223317] busted_srcud-torture: Free-Block Circulation: 96138 96138 96137 96136 96135 96134 96133 96132 96131 96130 0 [ 353.233509] busted_srcud-torture: Tiny SRCU per-CPU(idx=0): (0,0) gp: 746076->746076 [ 353.234283] busted_srcud-torture: rcu_torture_stats is stopping [ 353.306251] busted_srcud-torture: rtc: 00000000 VER: 96139 tfle: 0 rta: 96139 rtaf: 0 rtf: 96130 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbf: 0 rtb: 0 nt: 15738 barrier: 0/0:0 read-exits: 320 nocb-toggles: 0:0 [ 353.310875] busted_srcud-torture: !!! Reader Pipe: 6151759 36356 10612 405 11 0 0 0 0 0 0 [ 353.313546] busted_srcud-torture: Reader Batch: 6199143 0 0 0 0 0 0 0 0 0 0 [ 353.314297] busted_srcud-torture: Free-Block Circulation: 96138 96138 96137 96136 96135 96134 96133 96132 96131 96130 0 [ 353.315342] busted_srcud-torture: Tiny SRCU per-CPU(idx=0): (0,0) gp: 0->0 [ 353.334592] 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=0 onoff_holdoff=0 read_exit_delay=13 read_exit_burst=16 nocbs_nthreads=0 nocbs_toggle=1000 test_nmis=0