Linux Kernel Functional Testing (LKFT) started running rcu-torture tests on qemu_arm64, qemu_arm qemu_x86_64 and qemu_i386 from our CI build systems.
The following warning(s) noticed on qemu_i386 while running rcu-torture test on Linux mainline and Linux -next master branch. Since we do not have baseline results i can not comment this as regression but when compared with stable-rc 5.4 kernel this warning is new on mainline and next.
rcu-torture test log ---------------------- [ 8.191718] rcu-torture:--- Start of test: nreaders=3 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 [ 8.196860] rcu: Start-test grace-period state: g973 f0x2 [ 8.198122] rcu-torture: Creating rcu_torture_writer task [ 8.199823] rcu-torture: Creating rcu_torture_fakewriter task [ 8.199864] rcu-torture: rcu_torture_writer task started [ 8.201174] rcu-torture: Creating rcu_torture_fakewriter task [ 8.201245] rcu-torture: rcu_torture_fakewriter task started [ 8.202099] rcu_torture_writer: Testing conditional GPs. [ 8.203056] rcu-torture: Creating rcu_torture_fakewriter task [ 8.204450] rcu_torture_writer: Testing expedited GPs. [ 8.204474] rcu-torture: rcu_torture_fakewriter task started [ 8.205441] rcu-torture: Creating rcu_torture_fakewriter task [ 8.205483] rcu-torture: rcu_torture_fakewriter task started [ 8.206599] rcu_torture_writer: Testing asynchronous GPs. [ 8.206602] rcu_torture_writer: Testing normal GPs. [ 8.211609] rcu-torture: Creating rcu_torture_reader task [ 8.211743] rcu-torture: rcu_torture_fakewriter task started [ 8.212627] rcu-torture: Creating rcu_torture_reader task [ 8.212707] rcu-torture: rcu_torture_reader task started [ 8.215613] rcu-torture: Creating rcu_torture_reader task [ 8.215733] rcu-torture: rcu_torture_reader task started [ 8.216454] rcu-torture: Creating rcu_torture_stats task [ 8.217774] rcu-torture: rcu_torture_reader task started [ 8.218562] rcu-torture: Creating torture_shuffle task [ 8.219696] rcu-torture: rcu_torture_stats task started [ 8.220385] rcu-torture: Creating torture_stutter task [ 8.222150] rcu-torture: torture_shuffle task started [ 8.222990] rcu-torture: Creating rcu_torture_fwd_prog task [ 8.222999] rcu-torture: torture_stutter task started [ 8.224683] rcu-torture: Creating rcu_torture_read_exit task [ 8.224755] rcu-torture: rcu_torture_fwd_progress task started [ 8.225923] rcu-torture: rcu_torture_read_exit: Start of test modprobe-rcutorture pass [ 8.230300] Dumping ftrace buffer: [ 8.230659] (ftrace buffer empty) [ 8.231586] ------------[ cut here ]------------ [ 8.232483] rcu_torture_writer: rtort_pipe_count: 4 [ 8.233265] WARNING: CPU: 2 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] Broadcast messag[ 8.234683] Modules linked in: rcutorture torture e from systemd-j[ 8.235456] CPU: 2 PID: 407 Comm: rcu_torture_wri Not tainted 5.10.0-next-20201215 #2 ournald@intel-co[ 8.236755] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 re2-32 (Wed 2020[ 8.237910] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] -12-16 09:24:22 [ 8.238840] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f UTC):[ 8.241511] EAX: 00000027 EBX: 00000000 ECX: f51cd9d0 EDX: f51c3e68 [ 8.242438] ESI: f7872bf0 EDI: f7872bcc EBP: c1207f8c ESP: c1207f4c [ 8.243300] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 kernel[263]: [ [ 8.244219] CR0: 80050033 CR2: b7c8eb96 CR3: 1d9a0000 CR4: 003506d0 8.230300] Dump[ 8.245228] Call Trace: ing ftrace buffe[ 8.245752] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] r:[ 8.246573] kthread+0xe2/0x110 [ 8.247114] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 8.247978] ? kthread_park+0xa0/0xa0 [ 8.248483] ret_from_fork+0x1c/0x28 [ 8.249031] ---[ end trace f0d5ead0494363c8 ]---
Broadcast message from systemd-journald@intel-core2-32 (Wed 2020-12-16 09:24:22 UTC): kernel[263]: [ 8.230659] (ftrace buffer empty) [ 8.255498] rcu-torture: rcu_torture_read_exit: End of episode [ 8.191718] rcu-torture:--- Start of test: nreaders=3 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 rcutorture-start pass INFO: Running rcutorture for 600 seconds... [ 21.553686] rcu-torture: rcu_torture_read_exit: Start of episode [ 21.565513] rcu-torture: rcu_torture_read_exit: End of episode [ 35.123776] rcu-torture: rcu_torture_read_exit: Start of episode [ 35.145399] rcu-torture: rcu_torture_read_exit: End of episode [ 48.498693] rcu-torture: rcu_torture_read_exit: Start of episode [ 48.508719] rcu-torture: rcu_torture_read_exit: End of episode [ 50.870536] ------------[ cut here ]------------ [ 50.871283] rcu_torture_writer: rtort_pipe_count: 3 [ 50.872180] WARNING: CPU: 1 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 50.874884] Modules linked in: rcutorture torture [ 50.876156] CPU: 1 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 50.878280] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 50.880083] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 50.880978] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 50.883639] EAX: 00000027 EBX: 0000000d ECX: f51aa9d0 EDX: f51a0e68 [ 50.884763] ESI: f7872e18 EDI: f7872d04 EBP: c1207f8c ESP: c1207f4c [ 50.885800] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 50.886988] CR0: 80050033 CR2: 01e2e850 CR3: 026f6000 CR4: 003506d0 [ 50.888048] Call Trace: [ 50.888465] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 50.889471] kthread+0xe2/0x110 [ 50.890069] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 50.891171] ? kthread_park+0xa0/0xa0 [ 50.891801] ret_from_fork+0x1c/0x28 [ 50.892374] ---[ end trace f0d5ead0494363c9 ]--- [ 50.893237] ------------[ cut here ]------------ [ 50.894035] rcu_torture_writer: rtort_pipe_count: 5 [ 50.894947] WARNING: CPU: 1 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 50.896682] Modules linked in: rcutorture torture [ 50.897452] CPU: 1 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 50.898959] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 50.900357] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 50.901361] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 50.904305] EAX: 00000027 EBX: 00000013 ECX: f51aa9d0 EDX: f51a0e68 [ 50.905379] ESI: f7872e18 EDI: f7872d94 EBP: c1207f8c ESP: c1207f4c [ 50.906449] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 50.907606] CR0: 80050033 CR2: 01e2e850 CR3: 026f6000 CR4: 003506d0 [ 50.908699] Call Trace: [ 50.909150] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 50.910165] kthread+0xe2/0x110 [ 50.910770] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 50.911818] ? kthread_park+0xa0/0xa0 [ 50.912468] ret_from_fork+0x1c/0x28 [ 50.913104] ---[ end trace f0d5ead0494363ca ]--- [ 61.809941] rcu-torture: rcu_torture_read_exit: Start of episode [ 61.831604] rcu-torture: rcu_torture_read_exit: End of episode [ 70.833679] rcu-torture: rtc: 8da7414f ver: 3947 tfle: 0 rta: 3948 rtaf: 0 rtf: 3936 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 60924 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 84 [ 70.836316] rcu-torture: Reader Pipe: 73349046 5958 0 0 0 0 0 0 0 0 0 [ 70.837292] rcu-torture: Reader Batch: 73342557 12447 0 0 0 0 0 0 0 0 0 [ 70.838309] rcu-torture: Free-Block Circulation: 3948 3946 3945 3944 3943 3942 3941 3939 3938 3937 0 [ 75.188816] rcu-torture: rcu_torture_read_exit: Start of episode [ 75.205268] rcu-torture: rcu_torture_read_exit: End of episode [ 83.332733] rcu_torture_fwd_prog_nr: Duration 12493 cver 825 gps 1473 [ 83.507200] rcu_torture_fwd_prog_cr Duration 29 barrier: 17 pending 48369 n_launders: 135108 n_launders_sa: 23105 n_max_gps: 100 n_max_cbs: 75135 cver 4 gps 9 [ 83.509533] rcu_torture_fwd_cb_hist: Callback-invocation histogram (duration 48 jiffies): 1s/10: 210243:12 [ 88.562713] rcu-torture: rcu_torture_read_exit: Start of episode [ 88.580007] rcu-torture: rcu_torture_read_exit: End of episode [ 93.879744] ------------[ cut here ]------------ [ 93.880447] rcu_torture_writer: rtort_pipe_count: 4 [ 93.881303] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 93.883192] Modules linked in: rcutorture torture [ 93.883951] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 93.885334] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 93.886619] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 93.887454] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 93.890561] EAX: 00000027 EBX: 0000005a ECX: f51879d0 EDX: f517de68 [ 93.891678] ESI: f7872c08 EDI: f787343c EBP: c1207f8c ESP: c1207f4c [ 93.892633] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 93.893811] CR0: 80050033 CR2: b7435000 CR3: 0239a000 CR4: 003506d0 [ 93.894810] Call Trace: [ 93.895233] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 93.896258] kthread+0xe2/0x110 [ 93.896883] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 93.897897] ? kthread_park+0xa0/0xa0 [ 93.898485] ret_from_fork+0x1c/0x28 [ 93.899098] ---[ end trace f0d5ead0494363cb ]--- [ 101.937743] rcu-torture: rcu_torture_read_exit: Start of episode [ 101.943604] rcu-torture: rcu_torture_read_exit: End of episode [ 115.313700] rcu-torture: rcu_torture_read_exit: Start of episode [ 115.317066] rcu-torture: rcu_torture_read_exit: End of episode [ 128.690866] rcu-torture: rcu_torture_read_exit: Start of episode [ 128.710091] rcu-torture: rcu_torture_read_exit: End of episode [ 132.273932] rcu-torture: rtc: 534006fa ver: 8004 tfle: 0 rta: 8005 rtaf: 0 rtf: 7992 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 121506 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 169 [ 132.279351] rcu-torture: Reader Pipe: 144997915 11987 0 0 0 0 0 0 0 0 0 [ 132.281446] rcu-torture: Reader Batch: 144985493 24409 0 0 0 0 0 0 0 0 0 [ 132.283404] rcu-torture: Free-Block Circulation: 8004 8004 8003 8001 8000 7998 7996 7995 7994 7993 0 [ 136.886764] ------------[ cut here ]------------ [ 136.888083] rcu_torture_writer: rtort_pipe_count: 7 [ 136.889707] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.892439] Modules linked in: rcutorture torture [ 136.893572] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 136.895679] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 136.896989] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.897869] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 136.900449] EAX: 00000027 EBX: 00000018 ECX: f51879d0 EDX: f517de68 [ 136.901417] ESI: f78732b0 EDI: f7872e0c EBP: c1207f8c ESP: c1207f4c [ 136.902334] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 136.903338] CR0: 80050033 CR2: 01dfe948 CR3: 02213000 CR4: 003506d0 [ 136.904279] Call Trace: [ 136.904620] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 136.905496] kthread+0xe2/0x110 [ 136.906010] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 136.906961] ? kthread_park+0xa0/0xa0 [ 136.907506] ret_from_fork+0x1c/0x28 [ 136.908084] ---[ end trace f0d5ead0494363cc ]--- [ 136.908810] ------------[ cut here ]------------ [ 136.909462] rcu_torture_writer: rtort_pipe_count: 7 [ 136.910306] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.912209] Modules linked in: rcutorture torture [ 136.912936] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 136.914308] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 136.915556] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.916384] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 136.919065] EAX: 00000027 EBX: 00000023 ECX: f51879d0 EDX: f517de68 [ 136.920054] ESI: f78732b0 EDI: f7872f14 EBP: c1207f8c ESP: c1207f4c [ 136.920974] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 136.922004] CR0: 80050033 CR2: 01dfe948 CR3: 02213000 CR4: 003506d0 [ 136.922958] Call Trace: [ 136.923310] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 136.924198] kthread+0xe2/0x110 [ 136.924686] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 136.925532] ? kthread_park+0xa0/0xa0 [ 136.926100] ret_from_fork+0x1c/0x28 [ 136.926628] ---[ end trace f0d5ead0494363cd ]--- [ 136.927336] ------------[ cut here ]------------ [ 136.928027] rcu_torture_writer: rtort_pipe_count: 5 [ 136.928738] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.930145] Modules linked in: rcutorture torture [ 136.930879] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 136.932121] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 136.933294] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.934156] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 136.936797] EAX: 00000027 EBX: 0000002f ECX: f51879d0 EDX: f517de68 [ 136.937842] ESI: f78732b0 EDI: f7873034 EBP: c1207f8c ESP: c1207f4c [ 136.938916] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 136.940002] CR0: 80050033 CR2: 01dfe948 CR3: 02213000 CR4: 003506d0 [ 136.941046] Call Trace: [ 136.941437] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 136.942413] kthread+0xe2/0x110 [ 136.942998] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 136.944010] ? kthread_park+0xa0/0xa0 [ 136.944580] ret_from_fork+0x1c/0x28 [ 136.945204] ---[ end trace f0d5ead0494363ce ]--- [ 136.945973] ------------[ cut here ]------------ [ 136.946764] rcu_torture_writer: rtort_pipe_count: 2 [ 136.947546] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.949114] Modules linked in: rcutorture torture [ 136.949869] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 136.951223] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 136.952432] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.953292] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 136.956015] EAX: 00000027 EBX: 00000034 ECX: 00000027 EDX: f517de6c [ 136.957052] ESI: f78732b0 EDI: f78730ac EBP: c1207f8c ESP: c1207f4c [ 136.958066] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 136.959177] CR0: 80050033 CR2: 01dfe948 CR3: 02213000 CR4: 003506d0 [ 136.960169] Call Trace: [ 136.960574] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 136.961554] kthread+0xe2/0x110 [ 136.962116] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 136.963133] ? kthread_park+0xa0/0xa0 [ 136.963759] ret_from_fork+0x1c/0x28 [ 136.964329] ---[ end trace f0d5ead0494363cf ]--- [ 136.965075] ------------[ cut here ]------------ [ 136.965847] rcu_torture_writer: rtort_pipe_count: 3 [ 136.966724] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.968186] Modules linked in: rcutorture torture [ 136.968931] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 136.970238] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 136.971455] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.972295] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 136.975121] EAX: 00000027 EBX: 00000037 ECX: f51879d0 EDX: f517de68 [ 136.976704] ESI: f78732b0 EDI: f78730f4 EBP: c1207f8c ESP: c1207f4c [ 136.978794] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 136.980909] CR0: 80050033 CR2: 01dfe948 CR3: 02213000 CR4: 003506d0 [ 136.982918] Call Trace: [ 136.983840] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 136.985583] kthread+0xe2/0x110 [ 136.986776] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 136.987998] ? kthread_park+0xa0/0xa0 [ 136.988474] ret_from_fork+0x1c/0x28 [ 136.988994] ---[ end trace f0d5ead0494363d0 ]--- [ 136.989593] ------------[ cut here ]------------ [ 136.990235] rcu_torture_writer: rtort_pipe_count: 4 [ 136.990995] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.992425] Modules linked in: rcutorture torture [ 136.993153] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 136.994470] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 136.995696] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 136.996479] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 137.000802] EAX: 00000027 EBX: 0000003a ECX: f51879d0 EDX: f517de68 [ 137.001770] ESI: f78732b0 EDI: f787313c EBP: c1207f8c ESP: c1207f4c [ 137.002700] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 137.003616] CR0: 80050033 CR2: 01dfe948 CR3: 02213000 CR4: 003506d0 [ 137.004533] Call Trace: [ 137.004938] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 137.005859] kthread+0xe2/0x110 [ 137.006307] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 137.007211] ? kthread_park+0xa0/0xa0 [ 137.007795] ret_from_fork+0x1c/0x28 [ 137.008317] ---[ end trace f0d5ead0494363d1 ]--- [ 137.008994] ------------[ cut here ]------------ [ 137.009681] rcu_torture_writer: rtort_pipe_count: 3 [ 137.010394] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 137.011858] Modules linked in: rcutorture torture [ 137.012518] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 137.013825] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 137.015055] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 137.015931] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 137.018481] EAX: 00000027 EBX: 00000040 ECX: 00000027 EDX: f517de6c [ 137.019483] ESI: f78732b0 EDI: f78731cc EBP: c1207f8c ESP: c1207f4c [ 137.020507] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 137.021623] CR0: 80050033 CR2: 01dfe948 CR3: 02213000 CR4: 003506d0 [ 137.022858] Call Trace: [ 137.023288] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 137.024333] kthread+0xe2/0x110 [ 137.024960] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 137.026037] ? kthread_park+0xa0/0xa0 [ 137.026760] ret_from_fork+0x1c/0x28 [ 137.027364] ---[ end trace f0d5ead0494363d2 ]--- [ 137.028181] ------------[ cut here ]------------ [ 137.029011] rcu_torture_writer: rtort_pipe_count: 5 [ 137.029927] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 137.031676] Modules linked in: rcutorture torture [ 137.032474] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 137.034006] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 137.035480] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 137.036482] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 137.039609] EAX: 00000027 EBX: 00000042 ECX: f51879d0 EDX: f517de68 [ 137.040717] ESI: f78732b0 EDI: f78731fc EBP: c1207f8c ESP: c1207f4c [ 137.041842] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 137.043060] CR0: 80050033 CR2: 01dfe948 CR3: 02213000 CR4: 003506d0 [ 137.044170] Call Trace: [ 137.044605] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 137.045670] kthread+0xe2/0x110 [ 137.046227] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 137.047302] ? kthread_park+0xa0/0xa0 [ 137.047993] ret_from_fork+0x1c/0x28 [ 137.048612] ---[ end trace f0d5ead0494363d3 ]--- [ 137.049436] ------------[ cut here ]------------ [ 137.050276] rcu_torture_writer: rtort_pipe_count: 3 [ 137.051203] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 137.052983] Modules linked in: rcutorture torture [ 137.053860] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 137.055453] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 137.056876] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 137.057892] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 137.061002] EAX: 00000027 EBX: 00000043 ECX: f51879d0 EDX: f517de68 [ 137.062090] ESI: f78732b0 EDI: f7873214 EBP: c1207f8c ESP: c1207f4c [ 137.063197] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 137.064366] CR0: 80050033 CR2: 01dfe948 CR3: 02213000 CR4: 003506d0 [ 137.065505] Call Trace: [ 137.066004] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 137.067079] kthread+0xe2/0x110 [ 137.067621] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 137.068701] ? kthread_park+0xa0/0xa0 [ 137.069333] ret_from_fork+0x1c/0x28 [ 137.070018] ---[ end trace f0d5ead0494363d4 ]--- [ 142.073705] rcu-torture: rcu_torture_read_exit: Start of episode [ 142.088951] rcu-torture: rcu_torture_read_exit: End of episode [ 153.214282] rcu_torture_fwd_prog_nr: Duration 8357 cver 633 gps 1138 [ 153.403732] rcu_torture_fwd_prog_cr Duration 36 barrier: 21 pending 42167 n_launders: 143281 n_launders_sa: 131263 n_max_gps: 100 n_max_cbs: 73728 cver 3 gps 11 [ 153.405785] rcu_torture_fwd_cb_hist: Callback-invocation histogram (duration 59 jiffies): 1s/10: 217009:13 [ 155.609937] rcu-torture: rcu_torture_read_exit: Start of episode [ 155.624814] rcu-torture: rcu_torture_read_exit: End of episode [ 168.946827] rcu-torture: rcu_torture_read_exit: Start of episode [ 168.950063] rcu-torture: rcu_torture_read_exit: End of episode [ 182.322683] rcu-torture: rcu_torture_read_exit: Start of episode [ 182.332632] rcu-torture: rcu_torture_read_exit: End of episode [ 193.713783] rcu-torture: rtc: de3a40d9 ver: 12094 tfle: 0 rta: 12095 rtaf: 0 rtf: 12080 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 181149 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 237 [ 193.716334] rcu-torture: Reader Pipe: 217230509 17887 0 0 0 0 0 0 0 0 0 [ 193.717302] rcu-torture: Reader Batch: 217211460 36936 0 0 0 0 0 0 0 0 0 [ 193.718383] rcu-torture: Free-Block Circulation: 12094 12093 12092 12090 12089 12088 12087 12083 12082 12080 0 [ 195.698429] rcu-torture: rcu_torture_read_exit: Start of episode [ 195.706057] rcu-torture: rcu_torture_read_exit: End of episode [ 209.009727] rcu-torture: rcu_torture_read_exit: Start of episode [ 209.034830] rcu-torture: rcu_torture_read_exit: End of episode [ 222.707669] rcu-torture: rcu_torture_read_exit: Start of episode [ 222.717228] rcu-torture: rcu_torture_read_exit: End of episode [ 225.107668] rcu_torture_fwd_prog_nr: Duration 6423 cver 431 gps 674 [ 225.304625] rcu_torture_fwd_prog_cr Duration 30 barrier: 18 pending 45670 n_launders: 115071 n_launders_sa: 45670 n_max_gps: 100 n_max_cbs: 81535 cver 4 gps 9 [ 225.306721] rcu_torture_fwd_cb_hist: Callback-invocation histogram (duration 51 jiffies): 1s/10: 196606:12 [ 236.082696] rcu-torture: rcu_torture_read_exit: Start of episode [ 236.084184] rcu-torture: rcu_torture_read_exit: End of episode [ 249.842725] rcu-torture: rcu_torture_read_exit: Start of episode [ 249.862693] rcu-torture: rcu_torture_read_exit: End of episode [ 255.155700] rcu-torture: rtc: 5147673e ver: 16080 tfle: 0 rta: 16081 rtaf: 0 rtf: 16070 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 241269 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 322 [ 255.159747] rcu-torture: Reader Pipe: 289687395 23676 0 0 0 0 0 0 0 0 0 [ 255.160768] rcu-torture: Reader Batch: 289662008 49063 0 0 0 0 0 0 0 0 0 [ 255.161738] rcu-torture: Free-Block Circulation: 16080 16079 16078 16077 16075 16074 16073 16072 16071 16070 0 [ 263.345759] rcu-torture: rcu_torture_read_exit: Start of episode [ 263.357968] rcu-torture: rcu_torture_read_exit: End of episode [ 276.731679] rcu-torture: rcu_torture_read_exit: Start of episode [ 276.736175] rcu-torture: rcu_torture_read_exit: End of episode [ 290.610555] rcu-torture: rcu_torture_read_exit: Start of episode [ 290.613925] rcu-torture: rcu_torture_read_exit: End of episode [ 294.416672] rcu_torture_fwd_prog_nr: Duration 6473 cver 600 gps 1034 [ 294.594441] rcu_torture_fwd_prog_cr Duration 30 barrier: 15 pending 45334 n_launders: 111298 n_launders_sa: 109603 n_max_gps: 100 n_max_cbs: 66046 cver 7 gps 9 [ 294.596462] rcu_torture_fwd_cb_hist: Callback-invocation histogram (duration 47 jiffies): 1s/10: 177344:11 [ 303.930698] rcu-torture: rcu_torture_read_exit: Start of episode [ 303.956670] rcu-torture: rcu_torture_read_exit: End of episode [ 316.594672] rcu-torture: rtc: 8f3d94ed ver: 20172 tfle: 0 rta: 20172 rtaf: 0 rtf: 20156 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 300958 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 390 [ 316.597784] rcu-torture: Reader Pipe: 361208386 29738 0 0 0 0 0 0 0 0 0 [ 316.598799] rcu-torture: Reader Batch: 361176291 61833 0 0 0 0 0 0 0 0 0 [ 316.599808] rcu-torture: Free-Block Circulation: 20173 20171 20170 20169 20168 20167 20166 20162 20157 20156 0 [ 317.491787] rcu-torture: rcu_torture_read_exit: Start of episode [ 317.505037] rcu-torture: rcu_torture_read_exit: End of episode [ 330.801705] rcu-torture: rcu_torture_read_exit: Start of episode [ 330.821143] rcu-torture: rcu_torture_read_exit: End of episode [ 344.434695] rcu-torture: rcu_torture_read_exit: Start of episode [ 344.442238] rcu-torture: rcu_torture_read_exit: End of episode [ 357.745692] rcu-torture: rcu_torture_read_exit: Start of episode [ 357.756548] rcu-torture: rcu_torture_read_exit: End of episode [ 371.059226] rcu-torture: rcu_torture_read_exit: Start of episode [ 371.062913] rcu-torture: rcu_torture_read_exit: End of episode [ 373.393666] rcu_torture_fwd_prog_nr: Duration 15830 cver 1321 gps 2188 [ 373.569775] rcu_torture_fwd_prog_cr Duration 28 barrier: 15 pending 29689 n_launders: 57048 n_launders_sa: 20971 n_max_gps: 100 n_max_cbs: 64403 cver 4 gps 7 [ 373.573491] rcu_torture_fwd_cb_hist: Callback-invocation histogram (duration 46 jiffies): 1s/10: 121451:10 [ 378.033717] rcu-torture: rtc: 464d2699 ver: 24665 tfle: 0 rta: 24665 rtaf: 0 rtf: 24651 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 363383 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 475 [ 378.036372] rcu-torture: Reader Pipe: 434351896 36155 0 0 0 0 0 0 0 0 0 [ 378.037357] rcu-torture: Reader Batch: 434313110 74941 0 0 0 0 0 0 0 0 0 [ 378.038364] rcu-torture: Free-Block Circulation: 24664 24664 24662 24661 24660 24658 24654 24653 24652 24651 0 [ 384.370675] rcu-torture: rcu_torture_read_exit: Start of episode [ 384.390681] rcu-torture: rcu_torture_read_exit: End of episode [ 397.745729] rcu-torture: rcu_torture_read_exit: Start of episode [ 397.759462] rcu-torture: rcu_torture_read_exit: End of episode [ 411.326181] rcu-torture: rcu_torture_read_exit: Start of episode [ 411.339801] rcu-torture: rcu_torture_read_exit: End of episode [ 424.755666] rcu-torture: rcu_torture_read_exit: Start of episode [ 424.764716] rcu-torture: rcu_torture_read_exit: End of episode [ 438.323670] rcu-torture: rcu_torture_read_exit: Start of episode [ 438.396888] rcu-torture: rcu_torture_read_exit: End of episode [ 439.473735] rcu-torture: rtc: bb0c988a ver: 28319 tfle: 0 rta: 28320 rtaf: 0 rtf: 28306 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 421382 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 560 [ 439.476457] rcu-torture: Reader Pipe: 504507253 41547 0 0 0 0 0 0 0 0 0 [ 439.477456] rcu-torture: Reader Batch: 504462623 86177 0 0 0 0 0 0 0 0 0 [ 439.478487] rcu-torture: Free-Block Circulation: 28319 28318 28316 28314 28313 28311 28310 28309 28307 28306 0 [ 447.839666] rcu_torture_fwd_prog_nr: Duration 12252 cver 972 gps 1685 [ 448.014701] rcu_torture_fwd_prog_cr Duration 27 barrier: 15 pending 26505 n_launders: 129796 n_launders_sa: 101 n_max_gps: 100 n_max_cbs: 77442 cver 2 gps 10 [ 448.017945] rcu_torture_fwd_cb_hist: Callback-invocation histogram (duration 45 jiffies): 1s/10: 207238:13 [ 451.697700] rcu-torture: rcu_torture_read_exit: Start of episode [ 451.713990] rcu-torture: rcu_torture_read_exit: End of episode [ 459.446720] ------------[ cut here ]------------ [ 459.447333] rcu_torture_writer: rtort_pipe_count: 8 [ 459.448071] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 459.449496] Modules linked in: rcutorture torture [ 459.450233] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 459.451518] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 459.452691] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 459.453459] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 459.456034] EAX: 00000027 EBX: 00000007 ECX: f51879d0 EDX: f517de68 [ 459.456928] ESI: f7873208 EDI: f7872c74 EBP: c1207f8c ESP: c1207f4c [ 459.457828] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 459.458816] CR0: 80050033 CR2: 0960df5c CR3: 023ee000 CR4: 003506d0 [ 459.459719] Call Trace: [ 459.460062] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 459.460942] kthread+0xe2/0x110 [ 459.461403] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 459.462286] ? kthread_park+0xa0/0xa0 [ 459.462848] ret_from_fork+0x1c/0x28 [ 459.463335] ---[ end trace f0d5ead0494363d5 ]--- [ 459.464015] ------------[ cut here ]------------ [ 459.464706] rcu_torture_writer: rtort_pipe_count: 7 [ 459.465371] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 459.466841] Modules linked in: rcutorture torture [ 459.467509] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 459.468802] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 459.470003] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 459.470845] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 459.473403] EAX: 00000027 EBX: 0000003e ECX: 00000027 EDX: f517de6c [ 459.474329] ESI: f7873208 EDI: f787319c EBP: c1207f8c ESP: c1207f4c [ 459.475255] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 459.476220] CR0: 80050033 CR2: 0960df5c CR3: 023ee000 CR4: 003506d0 [ 459.477142] Call Trace: [ 459.477481] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 459.478365] kthread+0xe2/0x110 [ 459.478871] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 459.479811] ? kthread_park+0xa0/0xa0 [ 459.480363] ret_from_fork+0x1c/0x28 [ 459.480951] ---[ end trace f0d5ead0494363d6 ]--- [ 465.137695] rcu-torture: rcu_torture_read_exit: Start of episode [ 465.140903] rcu-torture: rcu_torture_read_exit: End of episode [ 478.577719] rcu-torture: rcu_torture_read_exit: Start of episode [ 478.592864] rcu-torture: rcu_torture_read_exit: End of episode [ 491.954723] rcu-torture: rcu_torture_read_exit: Start of episode [ 491.969890] rcu-torture: rcu_torture_read_exit: End of episode [ 500.913672] rcu-torture: rtc: 7ec35e96 ver: 32454 tfle: 0 rta: 32455 rtaf: 0 rtf: 32444 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 481015 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 628 [ 500.920282] rcu-torture: Reader Pipe: 575629825 47586 0 0 0 0 0 0 0 0 0 [ 500.922184] rcu-torture: Reader Batch: 575579057 98354 0 0 0 0 0 0 0 0 0 [ 500.924002] rcu-torture: Free-Block Circulation: 32454 32454 32453 32452 32451 32450 32449 32448 32447 32445 0 [ 505.586699] rcu-torture: rcu_torture_read_exit: Start of episode [ 505.600489] rcu-torture: rcu_torture_read_exit: End of episode [ 518.962750] rcu-torture: rcu_torture_read_exit: Start of episode [ 518.978017] rcu-torture: rcu_torture_read_exit: End of episode [ 519.472694] rcu_torture_fwd_prog_nr: Duration 10360 cver 706 gps 1175 [ 519.659707] rcu_torture_fwd_prog_cr Duration 34 barrier: 13 pending 32601 n_launders: 118865 n_launders_sa: 32601 n_max_gps: 100 n_max_cbs: 59919 cver 5 gps 10 [ 519.661865] rcu_torture_fwd_cb_hist: Callback-invocation histogram (duration 49 jiffies): 1s/10: 178784:12 [ 523.958007] ------------[ cut here ]------------ [ 523.958780] rcu_torture_writer: rtort_pipe_count: 3 [ 523.959453] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 523.960903] Modules linked in: rcutorture torture [ 523.961580] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 523.962919] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 523.964136] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 523.964978] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 523.967565] EAX: 00000027 EBX: 0000000d ECX: 00000027 EDX: f517de6c [ 523.968507] ESI: f7873268 EDI: f7872d04 EBP: c1207f8c ESP: c1207f4c [ 523.969426] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 523.970458] CR0: 80050033 CR2: 09314f5c CR3: 1d9a0000 CR4: 003506d0 [ 523.971378] Call Trace: [ 523.971803] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 523.972691] kthread+0xe2/0x110 [ 523.973145] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 523.974012] ? kthread_park+0xa0/0xa0 [ 523.974560] ret_from_fork+0x1c/0x28 [ 523.975127] ---[ end trace f0d5ead0494363d7 ]--- [ 523.975826] ------------[ cut here ]------------ [ 523.976463] rcu_torture_writer: rtort_pipe_count: 3 [ 523.977202] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 523.978681] Modules linked in: rcutorture torture [ 523.979335] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 523.980606] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 523.981852] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 523.982720] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 523.985256] EAX: 00000027 EBX: 0000001d ECX: f51879d0 EDX: f517de68 [ 523.986190] ESI: f7873268 EDI: f7872e84 EBP: c1207f8c ESP: c1207f4c [ 523.987106] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 523.988123] CR0: 80050033 CR2: 09314f5c CR3: 1d9a0000 CR4: 003506d0 [ 523.989042] Call Trace: [ 523.989397] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 523.990311] kthread+0xe2/0x110 [ 523.990822] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 523.991696] ? kthread_park+0xa0/0xa0 [ 523.992220] ret_from_fork+0x1c/0x28 [ 523.992762] ---[ end trace f0d5ead0494363d8 ]--- [ 523.993417] ------------[ cut here ]------------ [ 523.994099] rcu_torture_writer: rtort_pipe_count: 3 [ 523.994868] WARNING: CPU: 0 PID: 407 at kernel/rcu/rcutorture.c:1169 rcu_torture_writer+0x673/0x770 [rcutorture] [ 523.996299] Modules linked in: rcutorture torture [ 523.997009] CPU: 0 PID: 407 Comm: rcu_torture_wri Tainted: G W 5.10.0-next-20201215 #2 [ 523.998335] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.12.0-1 04/01/2014 [ 523.999523] EIP: rcu_torture_writer+0x673/0x770 [rcutorture] [ 524.000376] Code: f7 85 c0 75 13 b8 01 00 00 00 87 05 4c 28 87 f7 85 c0 0f 84 97 00 00 00 ff 77 fc 68 78 16 87 f7 68 19 03 87 f7 e8 6e a8 86 e5 <0f> 0b 83 c4 0c eb 9e 8d b6 00 00 00 00 e8 2b 2f ce e4 83 fb 03 0f [ 524.003009] EAX: 00000027 EBX: 0000001e ECX: f51879d0 EDX: f517de68 [ 524.003921] ESI: f7873268 EDI: f7872e9c EBP: c1207f8c ESP: c1207f4c [ 524.004854] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010296 [ 524.005838] CR0: 80050033 CR2: 09314f5c CR3: 1d9a0000 CR4: 003506d0 [ 524.006776] Call Trace: [ 524.007128] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 524.008017] kthread+0xe2/0x110 [ 524.008476] ? rcu_torture_read_exit_child+0x40/0x40 [rcutorture] [ 524.009362] ? kthread_park+0xa0/0xa0 [ 524.009931] ret_from_fork+0x1c/0x28 [ 524.010475] ---[ end trace f0d5ead0494363d9 ]--- [ 532.416286] rcu-torture: rcu_torture_read_exit: Start of episode [ 532.434712] rcu-torture: rcu_torture_read_exit: End of episode [ 545.779676] rcu-torture: rcu_torture_read_exit: Start of episode [ 545.795961] rcu-torture: rcu_torture_read_exit: End of episode [ 559.154678] rcu-torture: rcu_torture_read_exit: Start of episode [ 559.177267] rcu-torture: rcu_torture_read_exit: End of episode [ 562.353751] rcu-torture: rtc: c4aca2af ver: 36863 tfle: 0 rta: 36864 rtaf: 0 rtf: 36852 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 543473 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 713 [ 562.356467] rcu-torture: Reader Pipe: 649527074 54135 0 0 0 0 0 0 0 0 0 [ 562.357608] rcu-torture: Reader Batch: 649469764 111445 0 0 0 0 0 0 0 0 0 [ 562.358740] rcu-torture: Free-Block Circulation: 36863 36862 36861 36860 36858 36857 36856 36855 36854 36852 0 [ 572.593718] rcu-torture: rcu_torture_read_exit: Start of episode [ 572.602551] rcu-torture: rcu_torture_read_exit: End of episode [ 585.970759] rcu-torture: rcu_torture_read_exit: Start of episode [ 585.975978] rcu-torture: rcu_torture_read_exit: End of episode [ 595.984670] rcu_torture_fwd_prog_nr: Duration 13138 cver 790 gps 1388 [ 596.183830] rcu_torture_fwd_prog_cr Duration 38 barrier: 15 pending 43040 n_launders: 127889 n_launders_sa: 104125 n_max_gps: 100 n_max_cbs: 67685 cver 3 gps 10 [ 596.186079] rcu_torture_fwd_cb_hist: Callback-invocation histogram (duration 55 jiffies): 1s/10: 195574:13 [ 599.346737] rcu-torture: rcu_torture_read_exit: Start of episode [ 599.361681] rcu-torture: rcu_torture_read_exit: End of episode [ 612.662698] rcu-torture: rcu_torture_read_exit: Start of episode [ 612.801463] rcu-torture: rcu_torture_read_exit: End of episode [ 618.248862] rcu-torture: Stopping rcu_torture_reader [ 618.248862] rcu-torture: Stopping torture_shuffle task [ 618.249752] rcu-torture: Stopping rcu_torture_reader [ 618.252955] rcu-torture: Stopping torture_shuffle [ 618.254334] rcu-torture: Stopping torture_stutter task [ 618.256699] rcu-torture: Stopping torture_stutter [ 618.257484] rcu: rcu_sched: wait state: RCU_GP_WAIT_FQS(5) ->state: 0x402 delta ->gp_activity 4 ->gp_req_activity 261 ->gp_wake_time 261 ->gp_wake_seq 272805 ->gp_seq 272809 ->gp_seq_needed 272816 ->gp_flags 0x0 [ 618.260860] rcu: rcu_node 0:3 ->gp_seq 272809 ->gp_seq_needed 272816 [ 618.261883] rcu: cpu 0 ->gp_seq_needed 272816 [ 618.262618] rcu: cpu 1 ->gp_seq_needed 272816 [ 618.263364] rcu: cpu 2 ->gp_seq_needed 272812 [ 618.264141] rcu: cpu 3 ->gp_seq_needed 272816 [ 618.265089] rcu: RCU callbacks invoked since boot: 2381909 [ 618.266316] rcu_tasks: RTGS_INIT(0) since 318056 g:0 i:0/0 k. [ 618.267203] rcu_tasks_rude: RTGS_INIT(0) since 318057 g:0 i:0/0 k. [ 618.268144] rcu_tasks_trace: RTGS_INIT(0) since 318058 g:0 i:0/0 k. N0 h:0/0/0 [ 618.299515] rcu-torture: Stopping rcu_torture_reader [ 618.299585] rcu-torture: Stopping rcu_torture_fakewriter [ 618.302051] rcu-torture: Stopping rcu_torture_fakewriter [ 618.302209] rcu-torture: Stopping rcu_torture_fakewriter [ 618.304284] rcu-torture: Stopping rcu_torture_writer [ 618.305734] rcu-torture: Stopping rcu_torture_fakewriter [ 619.249840] rcu-torture: Stopping rcu_torture_read_exit [ 619.250000] rcu-torture: Stopping rcutorture_read_exit task [ 619.252823] rcu-torture: Stopping rcu_torture_fwd_prog task [ 620.401707] rcu_torture_fwd_prog: tested 8 tested_tries 9 [ 620.402596] rcu-torture: Stopping rcu_torture_fwd_prog [ 620.403580] rcu-torture: Stopping rcu_torture_writer task [ 620.405247] rcu-torture: Stopping rcu_torture_reader task [ 620.406615] rcu-torture: Stopping rcu_torture_reader task [ 620.407817] rcu-torture: Stopping rcu_torture_reader task [ 620.409431] rcu-torture: Stopping rcu_torture_fakewriter task [ 620.411135] rcu-torture: Stopping rcu_torture_fakewriter task [ 620.412773] rcu-torture: Stopping rcu_torture_fakewriter task [ 620.414392] rcu-torture: Stopping rcu_torture_fakewriter task [ 620.416074] rcu: End-test grace-period state: g272877 f0x0 total-gps=67976 [ 620.417940] rcu-torture: Stopping rcu_torture_stats task [ 620.419132] rcu-torture: rtc: 00000000 VER: 40657 tfle: 0 rta: 40657 rtaf: 0 rtf: 40647 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 599512 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 781 [ 620.423816] rcu-torture: Reader Pipe: 715152697 59638 0 0 0 0 0 0 0 0 0 [ 620.425933] rcu-torture: Reader Batch: 715089910 122425 0 0 0 0 0 0 0 0 0 [ 620.428019] rcu-torture: Free-Block Circulation: 40656 40655 40654 40653 40652 40651 40650 40649 40648 40647 0 [ 620.430867] rcu-torture: Stopping rcu_torture_stats [ 620.438806] rcu-torture: rtc: 00000000 VER: 40657 tfle: 0 rta: 40657 rtaf: 0 rtf: 40647 rtmbe: 0 rtbe: 0 rtbke: 0 rtbre: 0 rtbf: 0 rtb: 0 nt: 599512 onoff: 0/0:0/0 -1,0:-1,0 0:0 (HZ=1000) barrier: 0/0:0 read-exits: 781 [ 620.444478] rcu-torture: Reader Pipe: 715152697 59638 0 0 0 0 0 0 0 0 0 [ 620.446691] rcu-torture: Reader Batch: 715089910 122425 0 0 0 0 0 0 0 0 0 [ 620.448894] rcu-torture: Free-Block Circulation: 40656 40655 40654 40653 40652 40651 40650 40649 40648 40647 0 [ 620.451315] rcu-torture:--- End of test: SUCCESS: nreaders=3 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 rmmod-rcutorture pass [ 620.451315] rcu-torture:--- End of test: SUCCESS: nreaders=3 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
metadata: git branch: master git repo: https://gitlab.com/aroxell/lkft-linux-next git commit: 9317f948b0b188b8d2fded75957e6d42c460df1b git describe: next-20201215 make_kernelversion: 5.10.0 kernel-config: https://builds.tuxbuild.com/1ljRF6wqoO5OmOHSQgKBvEv8dxG/config
full test log link, https://lkft.validation.linaro.org/scheduler/job/2052992#L842