From mboxrd@z Thu Jan 1 00:00:00 1970 Content-Type: multipart/mixed; boundary="===============3638620547554651661==" MIME-Version: 1.0 From: Joel Fernandes To: lkp@lists.01.org Subject: Re: 039f56b399 ("make rcutorture always dump ftrace"): WARNING: CPU: 1 PID: 121 at kernel/trace/trace.c:3509 trace_find_next_entry Date: Thu, 25 Jun 2020 17:12:29 -0400 Message-ID: In-Reply-To: <5ef3e811.fdegRm8TALRwT0i6%lkp@intel.com> List-Id: --===============3638620547554651661== Content-Type: text/plain; charset="utf-8" MIME-Version: 1.0 Content-Transfer-Encoding: quoted-printable I have since deleted this branch. Does it take a while to catch up? On Wed, Jun 24, 2020 at 7:56 PM kernel test robot wrote: > > Greetings, > > 0day kernel testing robot got the below dmesg and the first bad commit is > > https://git.kernel.org/pub/scm/linux/kernel/git/jfern/linux.git rcu-segcb > > commit 039f56b3996656cf3a0bdc306fcd09fc02fe3fbb > Author: Joel Fernandes > AuthorDate: Tue Jun 23 16:33:45 2020 -0400 > Commit: Joel Fernandes > CommitDate: Tue Jun 23 16:34:30 2020 -0400 > > make rcutorture always dump ftrace > > Signed-off-by: Joel Fernandes > > c4b4fa5b86 Add tracing for rcu_pending > 039f56b399 make rcutorture always dump ftrace > +--------------------------------------------------------+------------+--= ----------+ > | | c4b4fa5b86 | 0= 39f56b399 | > +--------------------------------------------------------+------------+--= ----------+ > | boot_successes | 35 | 0= | > | boot_failures | 1 | 1= 7 | > | WARNING:held_lock_freed | 1 | 1= | > | is_freeing_memory#-#,with_a_lock_still_held_there | 1 | 1= | > | WARNING:at_kernel/trace/trace.c:#trace_find_next_entry | 0 | 1= 7 | > | EIP:trace_find_next_entry | 0 | 1= 7 | > +--------------------------------------------------------+------------+--= ----------+ > > If you fix the issue, kindly add following tag > Reported-by: kernel test robot > > [ 122.596183] rcu_tort-124 1d.h1 71613156us : invoke_rcu_core: Raisi= ng softirq > [ 122.596183] swapper/-1 0d.h. 71613173us : invoke_rcu_core: Raisi= ng softirq > [ 122.596183] rcu_tort-120 1d.h2 71621302us : invoke_rcu_core: Raisi= ng softirq > [ 122.596183] --------------------------------- > [ 122.596183] ------------[ cut here ]------------ > [ 122.596183] WARNING: CPU: 1 PID: 121 at kernel/trace/trace.c:3509 trac= e_find_next_entry+0xe9/0x270 > [ 122.596183] Modules linked in: > [ 122.596183] CPU: 1 PID: 121 Comm: rcu_torture_sta Not tainted 5.8.0-rc= 1-00179-g039f56b399665 #1 > [ 122.596183] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIO= S 1.12.0-1 04/01/2014 > [ 122.596183] EIP: trace_find_next_entry+0xe9/0x270 > [ 122.596183] Code: 8f 4e c4 01 83 15 0c 8f 4e c4 00 39 48 6c 73 6b 81 f= e 00 f5 4e c4 0f 85 25 01 00 00 83 05 10 8f 4e c4 01 83 15 14 8f 4e c4 00 <= 0f> 0b 83 05 20 8f 4e c4 01 83 15 24 8f 4e c4 00 eb 3f 8d 74 26 00 > [ 122.596183] EAX: c44ed400 EBX: c44ed400 ECX: 00000014 EDX: f1e360ec > [ 122.596183] ESI: c44ef500 EDI: f1e360ec EBP: ee7a9e60 ESP: ee7a9e48 > [ 122.596183] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068 EFLAGS: 00010= 046 > [ 122.596183] CR0: 80050033 CR2: ffffffff CR3: 04132000 CR4: 001406b0 > [ 122.596183] Call Trace: > [ 122.596183] trace_print_lat_context+0x47/0x620 > [ 122.596183] ? find_next_bit+0x1d/0x30 > [ 122.596183] ? cpumask_next+0x59/0x70 > [ 122.596183] print_trace_line+0x928/0xb60 > [ 122.596183] ftrace_dump+0x307/0x4b0 > [ 122.596183] rcu_torture_stats_print+0x908/0x9d0 > [ 122.596183] rcu_torture_stats+0x88/0x100 > [ 122.596183] kthread+0x244/0x260 > [ 122.596183] ? rcu_torture_stats_print+0x9d0/0x9d0 > [ 122.596183] ? kthread_unpark+0xd0/0xd0 > [ 122.596183] ret_from_fork+0x19/0x30 > [ 122.596183] ---[ end trace ca04e5844f44c5cc ]--- > [ 149.891205] [drm] Initialized vgem 1.0.0 20120112 for vgem on minor 0 > > # HH:MM RESULT = GOOD BAD GOOD_BUT_DIRTY DIRTY_NOT_BAD > git bisect start 82cf130fe4c5a6e6712f8bd07e42f6455cb9db4e 48778464bb7d346= b47157d21ffde2af6b2d39110 -- > git bisect good ea343062323ab751211db6e3931c41737584891e # 01:30 G = 10 0 1 1 Merge 'hid/for-next' into devel-catchup-202006242334 > git bisect bad d55c151aaabf8418b956d88b38864650c88a789c # 01:44 B = 0 1 17 0 Merge 'tip/ras/core' into devel-catchup-202006242334 > git bisect good 2b99151919ed84b0b4635936833d6873f81e7700 # 02:19 G = 11 0 2 3 Merge 'saeed/net-next' into devel-catchup-202006242334 > git bisect bad 8be1d8a5c01dc631735fee16545fd67b8b6c986e # 02:39 B = 0 1 17 0 Merge 'hch-misc/cleanup-kernel-rw-helpers.3' into devel-= catchup-202006242334 > git bisect good 53eaebe99597aa35661387ecdd53dcac64dedb01 # 03:19 G = 11 0 1 1 Merge 'mkp-scsi/fixes' into devel-catchup-202006242334 > git bisect bad 7761f825fef535e2f775c3d98bb6558639116d27 # 03:52 B = 0 1 17 0 Merge 'jfern/rcu-segcb' into devel-catchup-202006242334 > git bisect good e3be8c671913af551669f244f5977fb93eea27e2 # 04:22 G = 10 0 0 0 Merge branches 'doc.2020.06.17a', 'fixes.2020.06.17a', '= rcu-tasks.2020.06.17a', 'scale.2020.06.17a', 'srcu.2020.06.17a' and 'tortur= e.2020.06.17a' into HEAD > git bisect good a60a604d76885d24fcaa65dd1839046589f60766 # 04:44 G = 10 0 1 1 tools/memory-model: Make judgelitmus.sh handle scripted = Result: tag > git bisect good 04e5111a0192dce73b7ef58b8288e16b2855b378 # 05:07 G = 10 0 1 1 rcu/tree: Use static initializer for krc.lock > git bisect good 6f92ad84da8ba905864d5fe0e616494fb718a84e # 05:32 G = 10 0 2 2 rcu: Initialize at declaration time in rcu_exp_handler() > git bisect good 1fce90226d6138c0c8fe6f4a913119d4f0c15263 # 05:48 G = 10 0 1 1 rcu/trace: Use rsp's gp_seq in acceleration's rcu_grace_= period tracepoint > git bisect good b4ef7c752f22afcd8fa65809c8e6f974dc27714a # 06:25 G = 10 0 2 2 rcutree: Make rcu_advance also print Segcb after WAIT->D= ONE happens > git bisect bad 039f56b3996656cf3a0bdc306fcd09fc02fe3fbb # 06:37 B = 0 3 22 3 make rcutorture always dump ftrace > git bisect good c4b4fa5b86f7aca290f6218570980b451d17010a # 07:00 G = 10 0 0 0 Add tracing for rcu_pending > # first bad commit: [039f56b3996656cf3a0bdc306fcd09fc02fe3fbb] make rcuto= rture always dump ftrace > git bisect good c4b4fa5b86f7aca290f6218570980b451d17010a # 07:06 G = 32 0 1 2 Add tracing for rcu_pending > # extra tests with debug options > git bisect bad 039f56b3996656cf3a0bdc306fcd09fc02fe3fbb # 07:17 B = 0 2 19 1 make rcutorture always dump ftrace > # extra tests on head commit of jfern/rcu-segcb > git bisect bad 039f56b3996656cf3a0bdc306fcd09fc02fe3fbb # 07:33 B = 0 17 33 0 make rcutorture always dump ftrace > # bad: [039f56b3996656cf3a0bdc306fcd09fc02fe3fbb] make rcutorture always = dump ftrace > # extra tests on revert first bad commit > git bisect good a5800fbf9102456673f4fd9f2c9692dfa71392d8 # 07:55 G = 10 0 0 0 Revert "make rcutorture always dump ftrace" > # good: [a5800fbf9102456673f4fd9f2c9692dfa71392d8] Revert "make rcutortur= e always dump ftrace" > > --- > 0-DAY CI Kernel Test Service, Intel Corporation > https://lists.01.org/hyperkitty/list/lkp(a)lists.01.org --===============3638620547554651661==--