2022-11-30 20:34:50 sleep 300 2022-11-30 20:39:50 rmmod rcutorture [ 154.216364] 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 [ 154.226257] busted_srcud-torture: Creating rcu_torture_writer task [ 154.344440] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 154.346006] busted_srcud-torture: rcu_torture_writer task started [ 154.374010] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 154.375392] busted_srcud-torture: rcu_torture_fakewriter task started [ 154.381611] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 154.383793] busted_srcud-torture: rcu_torture_fakewriter task started [ 154.397473] busted_srcud-torture: Creating rcu_torture_fakewriter task [ 154.399262] busted_srcud-torture: rcu_torture_fakewriter task started [ 154.416216] busted_srcud-torture: Creating rcu_torture_reader task [ 154.417697] busted_srcud-torture: rcu_torture_fakewriter task started [ 154.589778] busted_srcud-torture: Creating rcu_torture_stats task [ 154.591270] busted_srcud-torture: rcu_torture_reader task started [ 154.627467] busted_srcud-torture: Creating torture_shuffle task [ 154.629419] busted_srcud-torture: rcu_torture_stats task started [ 154.640374] busted_srcud-torture: Creating torture_stutter task [ 154.644728] busted_srcud-torture: torture_shuffle task started [ 154.645963] busted_srcud-torture: rcu_torture_fwd_prog_init: Disabled, unsupported by RCU flavor under test [ 154.647399] busted_srcud-torture: Creating rcu_torture_read_exit task [ 154.649698] busted_srcud-torture: torture_stutter task started [ 154.679808] busted_srcud-torture: rcu_torture_read_exit: Start of test [ 160.204160] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 173.852307] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 179.931980] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 193.408396] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 193.764406] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 207.172665] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 209.945106] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 215.420183] busted_srcud-torture: rtc: 000000000279428e ver: 5235 tfle: 0 rta: 5235 rtaf: 0 rtf: 5226 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 671 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=250) barrier: 0/0:0 read-exits: 67 nocb-toggles: 0:0 [ 215.423271] busted_srcud-torture: Reader Pipe: 275424 0 0 0 0 0 0 0 0 0 0 [ 215.424601] busted_srcud-torture: Reader Batch: 275424 0 0 0 0 0 0 0 0 0 0 [ 215.425634] busted_srcud-torture: Free-Block Circulation: 5234 5234 5233 5232 5231 5230 5229 5228 5227 5226 0 [ 215.427157] rcu: busted_srcud-torture: Tree SRCU g38892 per-CPU(idx=1): 0(0,0 .) 1(0,0 .) T(0,0) [ 223.452091] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 223.475518] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 236.924198] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 239.654990] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 252.956114] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 252.976863] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 266.300134] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 269.644187] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 276.860219] busted_srcud-torture: rtc: 0000000050c8ad34 ver: 10408 tfle: 0 rta: 10408 rtaf: 0 rtf: 10399 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 3829 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=250) barrier: 0/0:0 read-exits: 135 nocb-toggles: 0:0 [ 276.864118] busted_srcud-torture: Reader Pipe: 1990957 0 0 0 0 0 0 0 0 0 0 [ 276.865301] busted_srcud-torture: Reader Batch: 1990957 0 0 0 0 0 0 0 0 0 0 [ 276.866565] busted_srcud-torture: Free-Block Circulation: 10407 10407 10406 10405 10404 10403 10402 10401 10400 10399 0 [ 276.868435] rcu: busted_srcud-torture: Tree SRCU g75952 per-CPU(idx=0): 0(0,0 .) 1(0,0 .) T(0,0) [ 283.296070] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 283.312402] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 296.636499] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 299.980847] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 313.468762] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 313.654465] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 326.972176] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 329.976207] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 338.300394] busted_srcud-torture: rtc: 00000000665cbc83 ver: 15387 tfle: 0 rta: 15387 rtaf: 0 rtf: 15378 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 6759 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=250) barrier: 0/0:0 read-exits: 203 nocb-toggles: 0:0 [ 338.305490] busted_srcud-torture: Reader Pipe: 3526635 0 0 0 0 0 0 0 0 0 0 [ 338.307024] busted_srcud-torture: Reader Batch: 3526635 0 0 0 0 0 0 0 0 0 0 [ 338.308924] busted_srcud-torture: Free-Block Circulation: 15386 15386 15385 15384 15383 15382 15381 15380 15379 15378 0 [ 338.310776] rcu: busted_srcud-torture: Tree SRCU g111892 per-CPU(idx=1): 0(0,0 .) 1(0,0 .) T(0,0) [ 343.325592] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 343.452572] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 356.924031] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 359.952452] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 373.536562] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 373.592630] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 386.940136] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 389.950676] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 399.740209] busted_srcud-torture: rtc: 000000005b42e880 ver: 20353 tfle: 0 rta: 20353 rtaf: 0 rtf: 20344 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 9726 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=250) barrier: 0/0:0 read-exits: 271 nocb-toggles: 0:0 [ 399.744204] busted_srcud-torture: Reader Pipe: 5120749 0 0 0 0 0 0 0 0 0 0 [ 399.745488] busted_srcud-torture: Reader Batch: 5120749 0 0 0 0 0 0 0 0 0 0 [ 399.746857] busted_srcud-torture: Free-Block Circulation: 20352 20352 20351 20350 20349 20348 20347 20346 20345 20344 0 [ 399.748802] rcu: busted_srcud-torture: Tree SRCU g148400 per-CPU(idx=0): 0(0,0 .) 1(0,0 .) T(0,0) [ 403.420578] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 403.447681] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 416.956033] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 419.949289] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 433.275965] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 433.297436] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 446.684113] busted_srcud-torture: rcu_torture_read_exit: Start of episode [ 449.937832] busted_srcud-torture: rcu_torture_read_exit: End of episode [ 455.106164] busted_srcud-torture: Stopping torture_shuffle task [ 455.107201] busted_srcud-torture: Stopping torture_shuffle [ 455.112573] busted_srcud-torture: Stopping torture_stutter task [ 455.121737] busted_srcud-torture: Stopping torture_stutter [ 455.123193] busted_srcud-torture: Stopping rcu_torture_reader [ 455.124469] busted_srcud-torture: Stopping rcu_torture_fakewriter [ 455.125614] busted_srcud-torture: Stopping rcu_torture_writer [ 455.126599] busted_srcud-torture: Stopping rcu_torture_fakewriter [ 455.127610] busted_srcud-torture: Stopping rcu_torture_fakewriter [ 455.128807] busted_srcud-torture: Stopping rcu_torture_fakewriter [ 455.292283] busted_srcud-torture: Stopping rcu_torture_read_exit [ 455.293356] busted_srcud-torture: Stopping rcutorture_read_exit task [ 455.294730] busted_srcud-torture: Stopping rcu_torture_writer task [ 455.301449] busted_srcud-torture: Stopping rcu_torture_reader task [ 455.302724] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 455.306797] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 455.308602] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 455.310258] busted_srcud-torture: Stopping rcu_torture_fakewriter task [ 455.313033] busted_srcud-torture: Stopping rcu_torture_stats task [ 455.320243] busted_srcud-torture: rtc: 0000000000000000 VER: 25518 tfle: 0 rta: 25518 rtaf: 0 rtf: 25509 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 12865 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=250) barrier: 0/0:0 read-exits: 339 nocb-toggles: 0:0 [ 455.323572] busted_srcud-torture: Reader Pipe: 6804945 0 0 0 0 0 0 0 0 0 0 [ 455.325017] busted_srcud-torture: Reader Batch: 6804945 0 0 0 0 0 0 0 0 0 0 [ 455.326199] busted_srcud-torture: Free-Block Circulation: 25517 25517 25516 25515 25514 25513 25512 25511 25510 25509 0 [ 455.328066] rcu: busted_srcud-torture: Tree SRCU g185400 per-CPU(idx=0): 0(0,0 .) 1(0,0 .) T(0,0) [ 455.329526] busted_srcud-torture: Stopping rcu_torture_stats [ 455.781336] busted_srcud-torture: rtc: 0000000000000000 VER: 25518 tfle: 0 rta: 25518 rtaf: 0 rtf: 25509 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 12865 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=250) barrier: 0/0:0 read-exits: 339 nocb-toggles: 0:0 [ 455.784843] busted_srcud-torture: Reader Pipe: 6804945 0 0 0 0 0 0 0 0 0 0 [ 455.785941] busted_srcud-torture: Reader Batch: 6804945 0 0 0 0 0 0 0 0 0 0 [ 455.787032] busted_srcud-torture: Free-Block Circulation: 25517 25517 25516 25515 25514 25513 25512 25511 25510 25509 0 [ 455.788792] rcu: busted_srcud-torture: Tree SRCU g0 per-CPU(idx=0): 0(0,0 .) 1(0,0 .) T(0,0) [ 455.790081] busted_srcud-torture:--- End of test: SUCCESS: 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