2022-07-31 14:13:33 modprobe rcutorture torture_type=tasks-tracing 2022-07-31 14:13:33 sleep 300 2022-07-31 14:18:33 rmmod rcutorture [ 36.765587] tasks-tracing-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 [ 36.795742] tasks-tracing-torture: Creating rcu_torture_writer task [ 36.798334] tasks-tracing-torture: Creating rcu_torture_fakewriter task [ 36.803776] tasks-tracing-torture: rcu_torture_writer task started [ 36.809641] tasks-tracing-torture: Creating rcu_torture_fakewriter task [ 36.812129] tasks-tracing-torture: rcu_torture_fakewriter task started [ 36.822813] tasks-tracing-torture: rcu_torture_fakewriter task started [ 36.824992] tasks-tracing-torture: Creating rcu_torture_fakewriter task [ 36.832495] tasks-tracing-torture: rcu_torture_fakewriter task started [ 36.834536] tasks-tracing-torture: Creating rcu_torture_fakewriter task [ 36.841509] tasks-tracing-torture: Creating rcu_torture_reader task [ 36.843557] tasks-tracing-torture: rcu_torture_fakewriter task started [ 36.850706] tasks-tracing-torture: Creating rcu_torture_stats task [ 36.852551] tasks-tracing-torture: rcu_torture_reader task started [ 36.861531] tasks-tracing-torture: Creating torture_shuffle task [ 36.863391] tasks-tracing-torture: rcu_torture_stats task started [ 36.870909] tasks-tracing-torture: torture_shuffle task started [ 36.874827] tasks-tracing-torture: Creating torture_stutter task [ 36.886672] tasks-tracing-torture: torture_stutter task started [ 36.886810] tasks-tracing-torture: Creating rcu_torture_fwd_prog task [ 36.897539] tasks-tracing-torture: Creating rcu_torture_read_exit task [ 36.897539] tasks-tracing-torture: rcu_torture_fwd_progress task started [ 36.914908] tasks-tracing-torture: rcu_torture_read_exit: Start of test [ 42.072431] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 55.400413] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 55.425256] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 76.007487] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 76.833309] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 90.407413] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 90.414148] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 97.640450] tasks-tracing-torture: rtc: ffffffffc03e0148 ver: 147 tfle: 0 rta: 148 rtaf: 0 rtf: 136 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 8396 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 67 nocb-toggles: 0:0 [ 97.665639] tasks-tracing-torture: Reader Pipe: 6500653 25 0 0 0 0 0 0 0 0 0 [ 97.667793] tasks-tracing-torture: Reader Batch: 6500678 0 0 0 0 0 0 0 0 0 0 [ 97.669870] tasks-tracing-torture: Free-Block Circulation: 147 146 144 143 141 140 139 138 137 136 0 [ 110.823444] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 112.929858] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 126.398103] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 126.438817] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 139.752423] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 142.967353] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 159.080525] tasks-tracing-torture: rtc: ffffffffc03e0c38 ver: 300 tfle: 0 rta: 300 rtaf: 0 rtf: 291 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 19386 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 118 nocb-toggles: 0:0 [ 159.086208] tasks-tracing-torture: Reader Pipe: 14391166 51 0 0 0 0 0 0 0 0 0 [ 159.093029] tasks-tracing-torture: Reader Batch: 14391217 0 0 0 0 0 0 0 0 0 0 [ 159.094932] tasks-tracing-torture: Free-Block Circulation: 299 299 298 297 296 295 294 293 292 291 0 [ 160.167424] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 163.096883] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 176.680397] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 176.970415] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 190.440441] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 193.088940] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 206.445383] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 206.465025] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 219.815514] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 220.528168] tasks-tracing-torture: rtc: ffffffffc03e03b0 ver: 459 tfle: 0 rta: 459 rtaf: 0 rtf: 450 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 28902 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 187 nocb-toggles: 0:0 [ 220.533649] tasks-tracing-torture: Reader Pipe: 21071752 75 0 0 0 0 0 0 0 0 0 [ 220.535741] tasks-tracing-torture: Reader Batch: 21071827 0 0 0 0 0 0 0 0 0 0 [ 220.537847] tasks-tracing-torture: Free-Block Circulation: 458 458 457 456 455 454 453 452 451 450 0 [ 223.008911] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 243.943476] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 244.022396] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 257.383415] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 257.390234] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 279.911896] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 281.571189] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 281.959914] tasks-tracing-torture: rtc: ffffffffc03e03b0 ver: 651 tfle: 0 rta: 652 rtaf: 0 rtf: 639 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 37452 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 254 nocb-toggles: 0:0 [ 281.965903] tasks-tracing-torture: Reader Pipe: 27695256 120 0 0 0 0 0 0 0 0 0 [ 281.968251] tasks-tracing-torture: Reader Batch: 27695376 0 0 0 0 0 0 0 0 0 0 [ 281.970371] tasks-tracing-torture: Free-Block Circulation: 651 650 648 645 644 643 642 641 640 639 0 [ 295.143432] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 295.192936] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 315.623446] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 317.533054] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 330.922429] tasks-tracing-torture: rcu_torture_read_exit: Start of episode [ 331.299820] tasks-tracing-torture: rcu_torture_read_exit: End of episode [ 336.998235] tasks-tracing-torture: Stopping torture_shuffle task [ 337.000915] tasks-tracing-torture: Stopping torture_shuffle [ 337.005663] tasks-tracing-torture: Stopping torture_stutter task [ 337.014510] tasks-tracing-torture: Stopping torture_stutter [ 337.016549] tasks-tracing-torture: Stopping rcu_torture_reader [ 337.024602] tasks-tracing-torture: Stopping rcu_torture_fakewriter [ 337.026562] tasks-tracing-torture: Stopping rcu_torture_fakewriter [ 337.029102] tasks-tracing-torture: Stopping rcu_torture_fakewriter [ 337.035235] tasks-tracing-torture: Stopping rcu_torture_writer [ 337.037122] tasks-tracing-torture: Stopping rcu_torture_fakewriter [ 337.447487] tasks-tracing-torture: Stopping rcu_torture_read_exit [ 337.447514] tasks-tracing-torture: Stopping rcutorture_read_exit task [ 337.452796] tasks-tracing-torture: Stopping rcu_torture_fwd_prog task [ 338.601338] tasks-tracing-torture: Stopping rcu_torture_fwd_prog [ 338.606036] tasks-tracing-torture: Stopping rcu_torture_writer task [ 338.608662] tasks-tracing-torture: Stopping rcu_torture_reader task [ 338.612824] tasks-tracing-torture: Stopping rcu_torture_fakewriter task [ 338.617311] tasks-tracing-torture: Stopping rcu_torture_fakewriter task [ 338.620527] tasks-tracing-torture: Stopping rcu_torture_fakewriter task [ 338.624061] tasks-tracing-torture: Stopping rcu_torture_fakewriter task [ 338.630356] tasks-tracing-torture: Stopping rcu_torture_stats task [ 338.632608] tasks-tracing-torture: rtc: 0000000000000000 VER: 789 tfle: 0 rta: 789 rtaf: 0 rtf: 780 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 46857 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 305 nocb-toggles: 0:0 [ 338.651264] tasks-tracing-torture: Reader Pipe: 34985279 137 0 0 0 0 0 0 0 0 0 [ 338.653634] tasks-tracing-torture: Reader Batch: 34985416 0 0 0 0 0 0 0 0 0 0 [ 338.655922] tasks-tracing-torture: Free-Block Circulation: 788 788 787 786 785 784 783 782 781 780 0 [ 338.658612] tasks-tracing-torture: Stopping rcu_torture_stats [ 338.977926] tasks-tracing-torture: rtc: 0000000000000000 VER: 789 tfle: 0 rta: 789 rtaf: 0 rtf: 780 rtmbe: 0 rtmbkf: 0/0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 46857 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 305 nocb-toggles: 0:0 [ 338.984047] tasks-tracing-torture: Reader Pipe: 34985279 137 0 0 0 0 0 0 0 0 0 [ 338.989230] tasks-tracing-torture: Reader Batch: 34985416 0 0 0 0 0 0 0 0 0 0 [ 338.991443] tasks-tracing-torture: Free-Block Circulation: 788 788 787 786 785 784 783 782 781 780 0 [ 338.996247] tasks-tracing-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